{"level":"warn","ts":"2026-04-11T21:45:46.467191Z","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-11T21:45:46.467306Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-11T21:45:46.467392Z","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-11T21:45:46.467406Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-11T21:45:46.467435Z","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-11T21:45:46.468267Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-11T21:45:46.468354Z","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://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-11T21:45:46.471867Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.21302ms"} {"level":"info","ts":"2026-04-11T21:45:46.479112Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-11T21:45:46.479201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T21:45:46.47922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-11T21:45:46.479233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T21:45:46.479241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-11T21:45:46.479271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-11T21:45:46.484401Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T21:45:46.486852Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T21:45:46.488143Z","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-11T21:45:46.49069Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T21:45:46.490913Z","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-11T21:45:46.491079Z","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-11T21:45:46.491099Z","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-11T21:45:46.49087Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T21:45:46.492055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-11T21:45:46.492874Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-11T21:45:46.49544Z","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-11T21:45:46.49552Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-11T21:45:46.495587Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-11T21:45:46.495707Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T21:45:46.495744Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T21:45:47.47951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T21:45:47.479596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T21:45:47.479638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-11T21:45:47.479661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-11T21:45:47.479674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-11T21:45:47.479693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-11T21:45:47.479715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-11T21:45:47.480735Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:47.48157Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T21:45:47.481569Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T21:45:47.481641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T21:45:47.481952Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T21:45:47.481989Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T21:45:47.482189Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:47.482428Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:47.482506Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T21:45:47.484488Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-11T21:45:47.485025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T21:46:17.361754Z","caller":"traceutil/trace.go:171","msg":"trace[572237968] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"113.814577ms","start":"2026-04-11T21:46:17.247907Z","end":"2026-04-11T21:46:17.361722Z","steps":["trace[572237968] 'process raft request' (duration: 97.162616ms)","trace[572237968] 'compare' (duration: 16.416715ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:46:17.361678Z","caller":"traceutil/trace.go:171","msg":"trace[999309386] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"112.661858ms","start":"2026-04-11T21:46:17.248986Z","end":"2026-04-11T21:46:17.361648Z","steps":["trace[999309386] 'read index received' (duration: 2.595954ms)","trace[999309386] 'applied index is now lower than readState.Index' (duration: 110.064784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:46:17.361973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.960396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7341"} {"level":"info","ts":"2026-04-11T21:46:17.362052Z","caller":"traceutil/trace.go:171","msg":"trace[38154651] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:535; }","duration":"113.101279ms","start":"2026-04-11T21:46:17.248937Z","end":"2026-04-11T21:46:17.362038Z","steps":["trace[38154651] 'agreement among raft nodes before linearized reading' (duration: 112.895074ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:17.362629Z","caller":"traceutil/trace.go:171","msg":"trace[1401889744] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"108.033383ms","start":"2026-04-11T21:46:17.254578Z","end":"2026-04-11T21:46:17.362611Z","steps":["trace[1401889744] 'process raft request' (duration: 107.843289ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:17.362754Z","caller":"traceutil/trace.go:171","msg":"trace[24320760] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"106.416485ms","start":"2026-04-11T21:46:17.256289Z","end":"2026-04-11T21:46:17.362706Z","steps":["trace[24320760] 'process raft request' (duration: 106.25144ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:23.314919Z","caller":"traceutil/trace.go:171","msg":"trace[66435741] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"163.572364ms","start":"2026-04-11T21:46:23.151323Z","end":"2026-04-11T21:46:23.314895Z","steps":["trace[66435741] 'process raft request' (duration: 162.801035ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:23.488058Z","caller":"traceutil/trace.go:171","msg":"trace[1727704249] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"155.730491ms","start":"2026-04-11T21:46:23.332301Z","end":"2026-04-11T21:46:23.488032Z","steps":["trace[1727704249] 'process raft request' (duration: 155.66946ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:23.488108Z","caller":"traceutil/trace.go:171","msg":"trace[1121513748] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"168.275369ms","start":"2026-04-11T21:46:23.319797Z","end":"2026-04-11T21:46:23.488072Z","steps":["trace[1121513748] 'process raft request' (duration: 168.023073ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:23.497739Z","caller":"traceutil/trace.go:171","msg":"trace[2027020992] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"125.933366ms","start":"2026-04-11T21:46:23.371744Z","end":"2026-04-11T21:46:23.497677Z","steps":["trace[2027020992] 'process raft request' (duration: 125.876553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:23.964852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.984192ms","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-11T21:46:23.964958Z","caller":"traceutil/trace.go:171","msg":"trace[1556772554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"170.143736ms","start":"2026-04-11T21:46:23.79479Z","end":"2026-04-11T21:46:23.964934Z","steps":["trace[1556772554] 'range keys from in-memory index tree' (duration: 169.782806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:23.965114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.168428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:46:23.965216Z","caller":"traceutil/trace.go:171","msg":"trace[1533734282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"106.306151ms","start":"2026-04-11T21:46:23.858881Z","end":"2026-04-11T21:46:23.965187Z","steps":["trace[1533734282] 'range keys from in-memory index tree' (duration: 106.023785ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:46:25.636732Z","caller":"traceutil/trace.go:171","msg":"trace[1584380468] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"144.709179ms","start":"2026-04-11T21:46:25.491998Z","end":"2026-04-11T21:46:25.636707Z","steps":["trace[1584380468] 'read index received' (duration: 144.581586ms)","trace[1584380468] 'applied index is now lower than readState.Index' (duration: 126.683µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:46:25.6368Z","caller":"traceutil/trace.go:171","msg":"trace[1357846466] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"192.907498ms","start":"2026-04-11T21:46:25.443848Z","end":"2026-04-11T21:46:25.636755Z","steps":["trace[1357846466] 'process raft request' (duration: 192.734354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:46:25.637067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.053358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-11T21:46:25.637132Z","caller":"traceutil/trace.go:171","msg":"trace[433558264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"145.167411ms","start":"2026-04-11T21:46:25.491951Z","end":"2026-04-11T21:46:25.637118Z","steps":["trace[433558264] 'agreement among raft nodes before linearized reading' (duration: 144.890244ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:47:18.717496Z","caller":"traceutil/trace.go:171","msg":"trace[565436816] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"137.337584ms","start":"2026-04-11T21:47:18.580134Z","end":"2026-04-11T21:47:18.717471Z","steps":["trace[565436816] 'process raft request' (duration: 137.16839ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:04.598834Z","caller":"traceutil/trace.go:171","msg":"trace[1119895167] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"116.772353ms","start":"2026-04-11T21:48:04.482035Z","end":"2026-04-11T21:48:04.598807Z","steps":["trace[1119895167] 'process raft request' (duration: 116.485096ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:04.760292Z","caller":"traceutil/trace.go:171","msg":"trace[1289990805] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"157.474351ms","start":"2026-04-11T21:48:04.602774Z","end":"2026-04-11T21:48:04.760248Z","steps":["trace[1289990805] 'process raft request' (duration: 111.973124ms)","trace[1289990805] 'compare' (duration: 45.307152ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:48:05.113194Z","caller":"traceutil/trace.go:171","msg":"trace[803371713] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"227.829395ms","start":"2026-04-11T21:48:04.885341Z","end":"2026-04-11T21:48:05.113171Z","steps":["trace[803371713] 'process raft request' (duration: 209.341656ms)","trace[803371713] 'compare' (duration: 18.190661ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:48:05.11896Z","caller":"traceutil/trace.go:171","msg":"trace[1423077408] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"222.889053ms","start":"2026-04-11T21:48:04.896042Z","end":"2026-04-11T21:48:05.118931Z","steps":["trace[1423077408] 'process raft request' (duration: 222.746979ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:18.022325Z","caller":"traceutil/trace.go:171","msg":"trace[2047052392] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2063; }","duration":"118.65298ms","start":"2026-04-11T21:48:17.90365Z","end":"2026-04-11T21:48:18.022303Z","steps":["trace[2047052392] 'read index received' (duration: 118.514147ms)","trace[2047052392] 'applied index is now lower than readState.Index' (duration: 137.713µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:48:18.022555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.874465ms","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-04-11T21:48:18.022397Z","caller":"traceutil/trace.go:171","msg":"trace[1393163621] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"134.368879ms","start":"2026-04-11T21:48:17.887987Z","end":"2026-04-11T21:48:18.022356Z","steps":["trace[1393163621] 'process raft request' (duration: 134.147034ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:18.022596Z","caller":"traceutil/trace.go:171","msg":"trace[90340144] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1993; }","duration":"118.961487ms","start":"2026-04-11T21:48:17.903624Z","end":"2026-04-11T21:48:18.022586Z","steps":["trace[90340144] 'agreement among raft nodes before linearized reading' (duration: 118.783562ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:48:48.908085Z","caller":"traceutil/trace.go:171","msg":"trace[1942870126] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"110.19931ms","start":"2026-04-11T21:48:48.79786Z","end":"2026-04-11T21:48:48.908059Z","steps":["trace[1942870126] 'process raft request' (duration: 110.012186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:48:55.7627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.187274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:48:55.762815Z","caller":"traceutil/trace.go:171","msg":"trace[1735406867] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2288; }","duration":"155.115192ms","start":"2026-04-11T21:48:55.607683Z","end":"2026-04-11T21:48:55.762798Z","steps":["trace[1735406867] 'read index received' (duration: 29.88µs)","trace[1735406867] 'applied index is now lower than readState.Index' (duration: 155.084172ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:48:55.762833Z","caller":"traceutil/trace.go:171","msg":"trace[365456] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"177.420286ms","start":"2026-04-11T21:48:55.585388Z","end":"2026-04-11T21:48:55.762809Z","steps":["trace[365456] 'process raft request' (duration: 17.478503ms)","trace[365456] 'compare' (duration: 159.052241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:48:55.763064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.344178ms","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-04-11T21:48:55.763159Z","caller":"traceutil/trace.go:171","msg":"trace[23920020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2211; }","duration":"155.491912ms","start":"2026-04-11T21:48:55.607648Z","end":"2026-04-11T21:48:55.76314Z","steps":["trace[23920020] 'agreement among raft nodes before linearized reading' (duration: 155.198205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:50:25.033789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.440883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:50:25.033882Z","caller":"traceutil/trace.go:171","msg":"trace[1168474478] transaction","detail":"{read_only:false; response_revision:2891; number_of_response:1; }","duration":"201.128672ms","start":"2026-04-11T21:50:24.83274Z","end":"2026-04-11T21:50:25.033869Z","steps":["trace[1168474478] 'process raft request' (duration: 69.527016ms)","trace[1168474478] 'compare' (duration: 131.126975ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:09.793574Z","caller":"traceutil/trace.go:171","msg":"trace[1735889905] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"120.025577ms","start":"2026-04-11T21:54:09.67352Z","end":"2026-04-11T21:54:09.793545Z","steps":["trace[1735889905] 'process raft request' (duration: 119.822642ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:09.801744Z","caller":"traceutil/trace.go:171","msg":"trace[1133113673] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"120.847177ms","start":"2026-04-11T21:54:09.680879Z","end":"2026-04-11T21:54:09.801726Z","steps":["trace[1133113673] 'process raft request' (duration: 120.609651ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:09.936837Z","caller":"traceutil/trace.go:171","msg":"trace[1972036536] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5059; }","duration":"112.215425ms","start":"2026-04-11T21:54:09.824598Z","end":"2026-04-11T21:54:09.936813Z","steps":["trace[1972036536] 'read index received' (duration: 76.422433ms)","trace[1972036536] 'applied index is now lower than readState.Index' (duration: 35.792172ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:09.936914Z","caller":"traceutil/trace.go:171","msg":"trace[1488905889] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"116.809978ms","start":"2026-04-11T21:54:09.820082Z","end":"2026-04-11T21:54:09.936892Z","steps":["trace[1488905889] 'process raft request' (duration: 81.022516ms)","trace[1488905889] 'compare' (duration: 35.592087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:54:09.937092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.559775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-11T21:54:09.937134Z","caller":"traceutil/trace.go:171","msg":"trace[308791942] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4878; }","duration":"108.657687ms","start":"2026-04-11T21:54:09.828465Z","end":"2026-04-11T21:54:09.937123Z","steps":["trace[308791942] 'agreement among raft nodes before linearized reading' (duration: 108.489293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:09.937268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.615674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-11T21:54:09.937386Z","caller":"traceutil/trace.go:171","msg":"trace[1111135896] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4878; }","duration":"112.761167ms","start":"2026-04-11T21:54:09.824578Z","end":"2026-04-11T21:54:09.937339Z","steps":["trace[1111135896] 'agreement among raft nodes before linearized reading' (duration: 112.336737ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:15.866555Z","caller":"traceutil/trace.go:171","msg":"trace[1517203108] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"140.116981ms","start":"2026-04-11T21:54:15.726413Z","end":"2026-04-11T21:54:15.86653Z","steps":["trace[1517203108] 'process raft request' (duration: 113.69539ms)","trace[1517203108] 'compare' (duration: 26.293878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:54:33.355178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.87448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:54:33.355304Z","caller":"traceutil/trace.go:171","msg":"trace[1834533351] linearizableReadLoop","detail":"{readStateIndex:5578; appliedIndex:5577; }","duration":"284.453358ms","start":"2026-04-11T21:54:33.070831Z","end":"2026-04-11T21:54:33.355285Z","steps":["trace[1834533351] 'read index received' (duration: 156.443344ms)","trace[1834533351] 'applied index is now lower than readState.Index' (duration: 128.008294ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:54:33.35541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.579261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T21:54:33.355445Z","caller":"traceutil/trace.go:171","msg":"trace[1246075186] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5383; }","duration":"284.639863ms","start":"2026-04-11T21:54:33.070795Z","end":"2026-04-11T21:54:33.355434Z","steps":["trace[1246075186] 'agreement among raft nodes before linearized reading' (duration: 284.569441ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:33.355437Z","caller":"traceutil/trace.go:171","msg":"trace[1492852900] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"318.466586ms","start":"2026-04-11T21:54:33.036938Z","end":"2026-04-11T21:54:33.355405Z","steps":["trace[1492852900] 'process raft request' (duration: 190.253747ms)","trace[1492852900] 'compare' (duration: 127.598263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:54:33.355686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:54:33.036917Z","time spent":"318.709532ms","remote":"127.0.0.1:45658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T21:54:33.545736Z","caller":"traceutil/trace.go:171","msg":"trace[1106044221] linearizableReadLoop","detail":"{readStateIndex:5579; appliedIndex:5578; }","duration":"190.37104ms","start":"2026-04-11T21:54:33.355332Z","end":"2026-04-11T21:54:33.545703Z","steps":["trace[1106044221] 'read index received' (duration: 190.129153ms)","trace[1106044221] 'applied index is now lower than readState.Index' (duration: 240.477µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:33.54582Z","caller":"traceutil/trace.go:171","msg":"trace[764114628] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"198.027339ms","start":"2026-04-11T21:54:33.347757Z","end":"2026-04-11T21:54:33.545784Z","steps":["trace[764114628] 'process raft request' (duration: 197.735771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:33.546057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.201815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21e38c9c-4151-4f1f-aad6-7b1003cbc189\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-11T21:54:33.546149Z","caller":"traceutil/trace.go:171","msg":"trace[1541755663] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-21e38c9c-4151-4f1f-aad6-7b1003cbc189; range_end:; response_count:1; response_revision:5384; }","duration":"399.330448ms","start":"2026-04-11T21:54:33.146787Z","end":"2026-04-11T21:54:33.546118Z","steps":["trace[1541755663] 'agreement among raft nodes before linearized reading' (duration: 399.077312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:33.546198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T21:54:33.146769Z","time spent":"399.42167ms","remote":"127.0.0.1:45612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-21e38c9c-4151-4f1f-aad6-7b1003cbc189\" "} {"level":"warn","ts":"2026-04-11T21:54:33.546077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.113289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-11T21:54:33.546312Z","caller":"traceutil/trace.go:171","msg":"trace[1384604709] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5384; }","duration":"150.371335ms","start":"2026-04-11T21:54:33.395925Z","end":"2026-04-11T21:54:33.546297Z","steps":["trace[1384604709] 'agreement among raft nodes before linearized reading' (duration: 149.961665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:48.985761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.008795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T21:54:48.985874Z","caller":"traceutil/trace.go:171","msg":"trace[969177733] linearizableReadLoop","detail":"{readStateIndex:5802; appliedIndex:5801; }","duration":"234.914777ms","start":"2026-04-11T21:54:48.750944Z","end":"2026-04-11T21:54:48.985859Z","steps":["trace[969177733] 'read index received' (duration: 84.623355ms)","trace[969177733] 'applied index is now lower than readState.Index' (duration: 150.289922ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T21:54:48.985928Z","caller":"traceutil/trace.go:171","msg":"trace[1453387515] transaction","detail":"{read_only:false; response_revision:5600; number_of_response:1; }","duration":"259.932954ms","start":"2026-04-11T21:54:48.725966Z","end":"2026-04-11T21:54:48.985899Z","steps":["trace[1453387515] 'process raft request' (duration: 109.682643ms)","trace[1453387515] 'compare' (duration: 149.7792ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T21:54:48.986009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.38642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-11T21:54:48.986047Z","caller":"traceutil/trace.go:171","msg":"trace[601059554] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5600; }","duration":"188.462512ms","start":"2026-04-11T21:54:48.797573Z","end":"2026-04-11T21:54:48.986036Z","steps":["trace[601059554] 'agreement among raft nodes before linearized reading' (duration: 188.377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:48.986072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.756366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T21:54:48.986297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.125299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131154"} {"level":"warn","ts":"2026-04-11T21:54:48.986109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.138443ms","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-04-11T21:54:48.986347Z","caller":"traceutil/trace.go:171","msg":"trace[143765958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5600; }","duration":"128.068984ms","start":"2026-04-11T21:54:48.858205Z","end":"2026-04-11T21:54:48.986274Z","steps":["trace[143765958] 'agreement among raft nodes before linearized reading' (duration: 127.760197ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:48.98639Z","caller":"traceutil/trace.go:171","msg":"trace[291117762] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5600; }","duration":"187.272523ms","start":"2026-04-11T21:54:48.799098Z","end":"2026-04-11T21:54:48.986371Z","steps":["trace[291117762] 'agreement among raft nodes before linearized reading' (duration: 186.943335ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:54:48.986405Z","caller":"traceutil/trace.go:171","msg":"trace[1744062386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5600; }","duration":"235.480601ms","start":"2026-04-11T21:54:48.7509Z","end":"2026-04-11T21:54:48.98638Z","steps":["trace[1744062386] 'agreement among raft nodes before linearized reading' (duration: 235.016119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T21:54:48.986488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.102555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-11T21:54:48.986564Z","caller":"traceutil/trace.go:171","msg":"trace[591909987] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5600; }","duration":"158.231608ms","start":"2026-04-11T21:54:48.828315Z","end":"2026-04-11T21:54:48.986547Z","steps":["trace[591909987] 'agreement among raft nodes before linearized reading' (duration: 157.670154ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:55:24.937541Z","caller":"traceutil/trace.go:171","msg":"trace[1910166978] transaction","detail":"{read_only:false; response_revision:5917; number_of_response:1; }","duration":"101.909061ms","start":"2026-04-11T21:55:24.835593Z","end":"2026-04-11T21:55:24.937502Z","steps":["trace[1910166978] 'process raft request' (duration: 101.713946ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T21:55:47.511173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-04-11T21:55:47.598653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"85.922038ms","hash":1142165483,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":31371264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-11T21:55:47.598744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1142165483,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-04-11T22:00:46.278759Z","caller":"traceutil/trace.go:171","msg":"trace[1816898642] linearizableReadLoop","detail":"{readStateIndex:8574; appliedIndex:8573; }","duration":"131.779419ms","start":"2026-04-11T22:00:46.146961Z","end":"2026-04-11T22:00:46.27874Z","steps":["trace[1816898642] 'read index received' (duration: 94.90759ms)","trace[1816898642] 'applied index is now lower than readState.Index' (duration: 36.870809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T22:00:46.278943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.945383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T22:00:46.279068Z","caller":"traceutil/trace.go:171","msg":"trace[680108903] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8286; }","duration":"132.088056ms","start":"2026-04-11T22:00:46.146956Z","end":"2026-04-11T22:00:46.279044Z","steps":["trace[680108903] 'agreement among raft nodes before linearized reading' (duration: 131.881781ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:00:46.451583Z","caller":"traceutil/trace.go:171","msg":"trace[1885051901] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"141.398213ms","start":"2026-04-11T22:00:46.310128Z","end":"2026-04-11T22:00:46.451526Z","steps":["trace[1885051901] 'process raft request' (duration: 141.151397ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:00:47.519001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6074} {"level":"info","ts":"2026-04-11T22:00:47.616052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6074,"took":"95.133985ms","hash":2831037781,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":23773184,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-11T22:00:47.616127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2831037781,"revision":6074,"compact-revision":3013} {"level":"info","ts":"2026-04-11T22:00:58.001731Z","caller":"traceutil/trace.go:171","msg":"trace[1903861038] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"103.816595ms","start":"2026-04-11T22:00:57.897879Z","end":"2026-04-11T22:00:58.001696Z","steps":["trace[1903861038] 'process raft request' (duration: 102.488772ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:27.810991Z","caller":"traceutil/trace.go:171","msg":"trace[2016945533] linearizableReadLoop","detail":"{readStateIndex:8860; appliedIndex:8859; }","duration":"208.720657ms","start":"2026-04-11T22:01:27.602244Z","end":"2026-04-11T22:01:27.810965Z","steps":["trace[2016945533] 'read index received' (duration: 208.501122ms)","trace[2016945533] 'applied index is now lower than readState.Index' (duration: 218.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T22:01:27.811065Z","caller":"traceutil/trace.go:171","msg":"trace[1012375981] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"379.849966ms","start":"2026-04-11T22:01:27.43118Z","end":"2026-04-11T22:01:27.81103Z","steps":["trace[1012375981] 'process raft request' (duration: 379.632151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:27.811186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.924781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-11T22:01:27.811219Z","caller":"traceutil/trace.go:171","msg":"trace[207721663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8560; }","duration":"209.010354ms","start":"2026-04-11T22:01:27.602199Z","end":"2026-04-11T22:01:27.811209Z","steps":["trace[207721663] 'agreement among raft nodes before linearized reading' (duration: 208.872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:27.811239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T22:01:27.431137Z","time spent":"380.00631ms","remote":"127.0.0.1:45658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T22:01:39.974679Z","caller":"traceutil/trace.go:171","msg":"trace[1993283447] linearizableReadLoop","detail":"{readStateIndex:8970; appliedIndex:8968; }","duration":"106.114051ms","start":"2026-04-11T22:01:39.868533Z","end":"2026-04-11T22:01:39.974647Z","steps":["trace[1993283447] 'read index received' (duration: 64.112657ms)","trace[1993283447] 'applied index is now lower than readState.Index' (duration: 41.999894ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T22:01:39.97511Z","caller":"traceutil/trace.go:171","msg":"trace[337049484] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"121.560854ms","start":"2026-04-11T22:01:39.853503Z","end":"2026-04-11T22:01:39.975063Z","steps":["trace[337049484] 'process raft request' (duration: 121.044652ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:39.975165Z","caller":"traceutil/trace.go:171","msg":"trace[1972065502] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"128.545892ms","start":"2026-04-11T22:01:39.846579Z","end":"2026-04-11T22:01:39.975125Z","steps":["trace[1972065502] 'process raft request' (duration: 86.135198ms)","trace[1972065502] 'compare' (duration: 41.593144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T22:01:39.97546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.90663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-11T22:01:39.975617Z","caller":"traceutil/trace.go:171","msg":"trace[1662529486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8665; }","duration":"107.098954ms","start":"2026-04-11T22:01:39.868494Z","end":"2026-04-11T22:01:39.975593Z","steps":["trace[1662529486] 'agreement among raft nodes before linearized reading' (duration: 106.785396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:40.755176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.375736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-11T22:01:40.755265Z","caller":"traceutil/trace.go:171","msg":"trace[1281348234] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:8672; }","duration":"239.496698ms","start":"2026-04-11T22:01:40.515746Z","end":"2026-04-11T22:01:40.755242Z","steps":["trace[1281348234] 'range keys from in-memory index tree' (duration: 239.192711ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:40.977836Z","caller":"traceutil/trace.go:171","msg":"trace[1283201736] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"134.909925ms","start":"2026-04-11T22:01:40.84291Z","end":"2026-04-11T22:01:40.97782Z","steps":["trace[1283201736] 'process raft request' (duration: 134.70338ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:40.977813Z","caller":"traceutil/trace.go:171","msg":"trace[198357749] linearizableReadLoop","detail":"{readStateIndex:8982; appliedIndex:8980; }","duration":"123.077329ms","start":"2026-04-11T22:01:40.854712Z","end":"2026-04-11T22:01:40.977789Z","steps":["trace[198357749] 'read index received' (duration: 23.855975ms)","trace[198357749] 'applied index is now lower than readState.Index' (duration: 99.220264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T22:01:40.978017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.285564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-11T22:01:40.97806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.33443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T22:01:40.97808Z","caller":"traceutil/trace.go:171","msg":"trace[1305284116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8676; }","duration":"123.384567ms","start":"2026-04-11T22:01:40.854684Z","end":"2026-04-11T22:01:40.978069Z","steps":["trace[1305284116] 'agreement among raft nodes before linearized reading' (duration: 123.177072ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:01:40.978109Z","caller":"traceutil/trace.go:171","msg":"trace[1350393198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8676; }","duration":"119.412122ms","start":"2026-04-11T22:01:40.858688Z","end":"2026-04-11T22:01:40.978101Z","steps":["trace[1350393198] 'agreement among raft nodes before linearized reading' (duration: 119.34488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T22:01:40.97827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.47136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j7c5f\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-11T22:01:40.978344Z","caller":"traceutil/trace.go:171","msg":"trace[1689020295] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j7c5f; range_end:; response_count:1; response_revision:8676; }","duration":"111.615513ms","start":"2026-04-11T22:01:40.866709Z","end":"2026-04-11T22:01:40.978324Z","steps":["trace[1689020295] 'agreement among raft nodes before linearized reading' (duration: 111.332636ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:02:04.414689Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002431.wal"} {"level":"info","ts":"2026-04-11T22:02:54.972392Z","caller":"traceutil/trace.go:171","msg":"trace[527899329] linearizableReadLoop","detail":"{readStateIndex:9735; appliedIndex:9734; }","duration":"113.681583ms","start":"2026-04-11T22:02:54.858685Z","end":"2026-04-11T22:02:54.972366Z","steps":["trace[527899329] 'read index received' (duration: 97.049593ms)","trace[527899329] 'applied index is now lower than readState.Index' (duration: 16.63065ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T22:02:54.972502Z","caller":"traceutil/trace.go:171","msg":"trace[729248659] transaction","detail":"{read_only:false; response_revision:9410; number_of_response:1; }","duration":"249.615674ms","start":"2026-04-11T22:02:54.722851Z","end":"2026-04-11T22:02:54.972467Z","steps":["trace[729248659] 'process raft request' (duration: 232.804529ms)","trace[729248659] 'compare' (duration: 16.518448ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T22:02:54.972569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.876408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T22:02:54.972628Z","caller":"traceutil/trace.go:171","msg":"trace[433993037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9410; }","duration":"113.979782ms","start":"2026-04-11T22:02:54.858632Z","end":"2026-04-11T22:02:54.972612Z","steps":["trace[433993037] 'agreement among raft nodes before linearized reading' (duration: 113.862208ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T22:03:30.112082Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-11T22:03:30.116864Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-11T22:03:30.116968Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-11T22:05:47.524398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8293} {"level":"info","ts":"2026-04-11T22:05:47.603806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8293,"took":"77.700128ms","hash":1940165940,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":26034176,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-11T22:05:47.603881Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1940165940,"revision":8293,"compact-revision":6074} {"level":"info","ts":"2026-04-11T22:10:47.534394Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10685} {"level":"info","ts":"2026-04-11T22:10:47.607277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10685,"took":"69.753637ms","hash":2302036685,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":20803584,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-11T22:10:47.607324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2302036685,"revision":10685,"compact-revision":8293}