{"level":"warn","ts":"2026-04-07T01:25:00.341238Z","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-07T01:25:00.341326Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-07T01:25:00.341388Z","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-07T01:25:00.341403Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-07T01:25:00.341423Z","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-07T01:25:00.342101Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-04-07T01:25:00.34217Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-07T01:25:00.345843Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.486287ms"} {"level":"info","ts":"2026-04-07T01:25:00.355385Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-04-07T01:25:00.355445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T01:25:00.355462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-04-07T01:25:00.35547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T01:25:00.355479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-04-07T01:25:00.355508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-04-07T01:25:00.363796Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T01:25:00.367652Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T01:25:00.368638Z","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-07T01:25:00.371402Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T01:25:00.371565Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T01:25:00.371637Z","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-07T01:25:00.371803Z","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-07T01:25:00.37186Z","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-07T01:25:00.373296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-04-07T01:25:00.373976Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-07T01:25:00.37684Z","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-07T01:25:00.376983Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-07T01:25:00.377032Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-07T01:25:00.377199Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T01:25:00.377249Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T01:25:00.456442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T01:25:00.456485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T01:25:00.456552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-04-07T01:25:00.456577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-04-07T01:25:00.456589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-07T01:25:00.456608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-04-07T01:25:00.456631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-07T01:25:00.457701Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T01:25:00.457862Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:25:00.457911Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:25:00.458022Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:25:00.459099Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:25:00.459411Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:25:00.459485Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:25:00.461384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-04-07T01:25:00.461488Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T01:25:00.461532Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T01:25:00.461684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T01:25:27.484982Z","caller":"traceutil/trace.go:171","msg":"trace[588309097] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"122.245017ms","start":"2026-04-07T01:25:27.362714Z","end":"2026-04-07T01:25:27.484959Z","steps":["trace[588309097] 'process raft request' (duration: 122.116174ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:32.139167Z","caller":"traceutil/trace.go:171","msg":"trace[1501182693] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"118.818152ms","start":"2026-04-07T01:25:32.02032Z","end":"2026-04-07T01:25:32.139138Z","steps":["trace[1501182693] 'read index received' (duration: 118.504015ms)","trace[1501182693] 'applied index is now lower than readState.Index' (duration: 312.647µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:32.139255Z","caller":"traceutil/trace.go:171","msg":"trace[1807220161] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"126.277567ms","start":"2026-04-07T01:25:32.01294Z","end":"2026-04-07T01:25:32.139218Z","steps":["trace[1807220161] 'process raft request' (duration: 126.011991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:32.13935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.968435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:32.139461Z","caller":"traceutil/trace.go:171","msg":"trace[913881318] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:588; }","duration":"119.146649ms","start":"2026-04-07T01:25:32.020296Z","end":"2026-04-07T01:25:32.139443Z","steps":["trace[913881318] 'agreement among raft nodes before linearized reading' (duration: 118.961896ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:36.087496Z","caller":"traceutil/trace.go:171","msg":"trace[1701119506] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"328.347803ms","start":"2026-04-07T01:25:35.759123Z","end":"2026-04-07T01:25:36.087471Z","steps":["trace[1701119506] 'process raft request' (duration: 328.19974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:36.088129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:35.759107Z","time spent":"328.463935ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:25:36.192629Z","caller":"traceutil/trace.go:171","msg":"trace[487976264] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"115.08327ms","start":"2026-04-07T01:25:36.077517Z","end":"2026-04-07T01:25:36.192601Z","steps":["trace[487976264] 'read index received' (duration: 9.941322ms)","trace[487976264] 'applied index is now lower than readState.Index' (duration: 105.140958ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:36.192805Z","caller":"traceutil/trace.go:171","msg":"trace[1441681526] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"334.197407ms","start":"2026-04-07T01:25:35.858583Z","end":"2026-04-07T01:25:36.192781Z","steps":["trace[1441681526] 'process raft request' (duration: 333.479542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:36.192898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.361746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:36.192966Z","caller":"traceutil/trace.go:171","msg":"trace[631041733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"115.465548ms","start":"2026-04-07T01:25:36.07749Z","end":"2026-04-07T01:25:36.192956Z","steps":["trace[631041733] 'agreement among raft nodes before linearized reading' (duration: 115.314665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:36.192954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:35.858555Z","time spent":"334.312798ms","remote":"127.0.0.1:59994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:25:51.013133Z","caller":"traceutil/trace.go:171","msg":"trace[1010648192] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"143.69247ms","start":"2026-04-07T01:25:50.868371Z","end":"2026-04-07T01:25:51.012063Z","steps":["trace[1010648192] 'process raft request' (duration: 143.482336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:06.396823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.043386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T01:27:06.397018Z","caller":"traceutil/trace.go:171","msg":"trace[259931687] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1839; }","duration":"257.312971ms","start":"2026-04-07T01:27:06.139667Z","end":"2026-04-07T01:27:06.39698Z","steps":["trace[259931687] 'range keys from in-memory index tree' (duration: 256.748121ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:06.435283Z","caller":"traceutil/trace.go:171","msg":"trace[1074409138] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"243.705402ms","start":"2026-04-07T01:27:06.191556Z","end":"2026-04-07T01:27:06.435262Z","steps":["trace[1074409138] 'read index received' (duration: 243.510069ms)","trace[1074409138] 'applied index is now lower than readState.Index' (duration: 194.443µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:06.435502Z","caller":"traceutil/trace.go:171","msg":"trace[247574683] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"274.607459ms","start":"2026-04-07T01:27:06.160872Z","end":"2026-04-07T01:27:06.43548Z","steps":["trace[247574683] 'process raft request' (duration: 274.240403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:06.435575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.992527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b44f6d79-bf75-4c1d-b6cd-7dff63651d16\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T01:27:06.435639Z","caller":"traceutil/trace.go:171","msg":"trace[1497998054] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b44f6d79-bf75-4c1d-b6cd-7dff63651d16; range_end:; response_count:1; response_revision:1840; }","duration":"244.12291ms","start":"2026-04-07T01:27:06.191499Z","end":"2026-04-07T01:27:06.435621Z","steps":["trace[1497998054] 'agreement among raft nodes before linearized reading' (duration: 243.927096ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:06.647599Z","caller":"traceutil/trace.go:171","msg":"trace[1647340684] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"118.334629ms","start":"2026-04-07T01:27:06.529242Z","end":"2026-04-07T01:27:06.647576Z","steps":["trace[1647340684] 'read index received' (duration: 34.957494ms)","trace[1647340684] 'applied index is now lower than readState.Index' (duration: 83.376375ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:06.647639Z","caller":"traceutil/trace.go:171","msg":"trace[577228778] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"203.08096ms","start":"2026-04-07T01:27:06.444532Z","end":"2026-04-07T01:27:06.647613Z","steps":["trace[577228778] 'process raft request' (duration: 119.665224ms)","trace[577228778] 'compare' (duration: 83.229892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:06.647958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.668785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b44f6d79-bf75-4c1d-b6cd-7dff63651d16\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-04-07T01:27:06.648101Z","caller":"traceutil/trace.go:171","msg":"trace[545613864] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b44f6d79-bf75-4c1d-b6cd-7dff63651d16; range_end:; response_count:1; response_revision:1841; }","duration":"118.888579ms","start":"2026-04-07T01:27:06.529177Z","end":"2026-04-07T01:27:06.648066Z","steps":["trace[545613864] 'agreement among raft nodes before linearized reading' (duration: 118.521402ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:56.223177Z","caller":"traceutil/trace.go:171","msg":"trace[833241814] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"112.499814ms","start":"2026-04-07T01:27:56.110649Z","end":"2026-04-07T01:27:56.223149Z","steps":["trace[833241814] 'process raft request' (duration: 112.353771ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:56.420681Z","caller":"traceutil/trace.go:171","msg":"trace[1467400414] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2247; }","duration":"115.006082ms","start":"2026-04-07T01:27:56.305659Z","end":"2026-04-07T01:27:56.420665Z","steps":["trace[1467400414] 'read index received' (duration: 114.844729ms)","trace[1467400414] 'applied index is now lower than readState.Index' (duration: 160.713µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:56.420729Z","caller":"traceutil/trace.go:171","msg":"trace[347197635] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"267.022523ms","start":"2026-04-07T01:27:56.153679Z","end":"2026-04-07T01:27:56.420701Z","steps":["trace[347197635] 'process raft request' (duration: 266.87801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:56.420896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.226326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-07T01:27:56.420921Z","caller":"traceutil/trace.go:171","msg":"trace[1761345718] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2169; }","duration":"115.319898ms","start":"2026-04-07T01:27:56.305594Z","end":"2026-04-07T01:27:56.420913Z","steps":["trace[1761345718] 'agreement among raft nodes before linearized reading' (duration: 115.189245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:56.6777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.900765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:27:56.677984Z","caller":"traceutil/trace.go:171","msg":"trace[992708440] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"253.475726ms","start":"2026-04-07T01:27:56.42447Z","end":"2026-04-07T01:27:56.677946Z","steps":["trace[992708440] 'process raft request' (duration: 108.026151ms)","trace[992708440] 'compare' (duration: 144.64968ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:56.678014Z","caller":"traceutil/trace.go:171","msg":"trace[756700691] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"252.945806ms","start":"2026-04-07T01:27:56.425046Z","end":"2026-04-07T01:27:56.677991Z","steps":["trace[756700691] 'process raft request' (duration: 252.820914ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:29:19.687259Z","caller":"traceutil/trace.go:171","msg":"trace[1391411048] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"253.665873ms","start":"2026-04-07T01:29:19.433556Z","end":"2026-04-07T01:29:19.687221Z","steps":["trace[1391411048] 'process raft request' (duration: 213.858156ms)","trace[1391411048] 'compare' (duration: 38.931091ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:31:44.946433Z","caller":"traceutil/trace.go:171","msg":"trace[2075312576] linearizableReadLoop","detail":"{readStateIndex:3922; appliedIndex:3921; }","duration":"158.600623ms","start":"2026-04-07T01:31:44.787806Z","end":"2026-04-07T01:31:44.946407Z","steps":["trace[2075312576] 'read index received' (duration: 158.391819ms)","trace[2075312576] 'applied index is now lower than readState.Index' (duration: 207.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:31:44.946585Z","caller":"traceutil/trace.go:171","msg":"trace[436775368] transaction","detail":"{read_only:false; response_revision:3780; number_of_response:1; }","duration":"236.076236ms","start":"2026-04-07T01:31:44.710454Z","end":"2026-04-07T01:31:44.946531Z","steps":["trace[436775368] 'process raft request' (duration: 235.804191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:31:44.946648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.826497ms","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-07T01:31:44.946847Z","caller":"traceutil/trace.go:171","msg":"trace[679602712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3780; }","duration":"159.056022ms","start":"2026-04-07T01:31:44.787757Z","end":"2026-04-07T01:31:44.946813Z","steps":["trace[679602712] 'agreement among raft nodes before linearized reading' (duration: 158.759176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:02.695225Z","caller":"traceutil/trace.go:171","msg":"trace[1801874606] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"107.38258ms","start":"2026-04-07T01:33:02.587822Z","end":"2026-04-07T01:33:02.695205Z","steps":["trace[1801874606] 'process raft request' (duration: 107.279568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:09.498205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.261832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:33:09.498317Z","caller":"traceutil/trace.go:171","msg":"trace[1318095240] linearizableReadLoop","detail":"{readStateIndex:5155; appliedIndex:5154; }","duration":"144.325373ms","start":"2026-04-07T01:33:09.353974Z","end":"2026-04-07T01:33:09.4983Z","steps":["trace[1318095240] 'read index received' (duration: 38.859588ms)","trace[1318095240] 'applied index is now lower than readState.Index' (duration: 105.464345ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:09.498581Z","caller":"traceutil/trace.go:171","msg":"trace[476635831] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"182.929976ms","start":"2026-04-07T01:33:09.315622Z","end":"2026-04-07T01:33:09.498552Z","steps":["trace[476635831] 'process raft request' (duration: 77.234846ms)","trace[476635831] 'compare' (duration: 105.009087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:33:09.498714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.736281ms","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-07T01:33:09.498754Z","caller":"traceutil/trace.go:171","msg":"trace[285261379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4969; }","duration":"144.808812ms","start":"2026-04-07T01:33:09.353935Z","end":"2026-04-07T01:33:09.498744Z","steps":["trace[285261379] 'agreement among raft nodes before linearized reading' (duration: 144.638019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:09.498841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.791905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T01:33:09.498951Z","caller":"traceutil/trace.go:171","msg":"trace[978140974] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4969; }","duration":"141.964449ms","start":"2026-04-07T01:33:09.356964Z","end":"2026-04-07T01:33:09.498929Z","steps":["trace[978140974] 'agreement among raft nodes before linearized reading' (duration: 141.653823ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:09.928085Z","caller":"traceutil/trace.go:171","msg":"trace[1072486096] linearizableReadLoop","detail":"{readStateIndex:5160; appliedIndex:5159; }","duration":"112.342264ms","start":"2026-04-07T01:33:09.81571Z","end":"2026-04-07T01:33:09.928052Z","steps":["trace[1072486096] 'read index received' (duration: 78.097133ms)","trace[1072486096] 'applied index is now lower than readState.Index' (duration: 34.240041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:33:09.928345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.621128ms","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-04-07T01:33:09.928385Z","caller":"traceutil/trace.go:171","msg":"trace[2952839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4974; }","duration":"112.746501ms","start":"2026-04-07T01:33:09.815628Z","end":"2026-04-07T01:33:09.928374Z","steps":["trace[2952839] 'agreement among raft nodes before linearized reading' (duration: 112.550487ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:09.928365Z","caller":"traceutil/trace.go:171","msg":"trace[973114685] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"116.137014ms","start":"2026-04-07T01:33:09.8122Z","end":"2026-04-07T01:33:09.928337Z","steps":["trace[973114685] 'process raft request' (duration: 81.698299ms)","trace[973114685] 'compare' (duration: 33.983977ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:18.557702Z","caller":"traceutil/trace.go:171","msg":"trace[1080098336] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"112.375215ms","start":"2026-04-07T01:33:18.445302Z","end":"2026-04-07T01:33:18.557677Z","steps":["trace[1080098336] 'process raft request' (duration: 112.188852ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:26.70669Z","caller":"traceutil/trace.go:171","msg":"trace[109176475] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"112.191191ms","start":"2026-04-07T01:33:26.594462Z","end":"2026-04-07T01:33:26.706653Z","steps":["trace[109176475] 'process raft request' (duration: 111.995526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:38.515735Z","caller":"traceutil/trace.go:171","msg":"trace[3773591] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5694; }","duration":"137.868141ms","start":"2026-04-07T01:33:38.377846Z","end":"2026-04-07T01:33:38.515714Z","steps":["trace[3773591] 'read index received' (duration: 137.677218ms)","trace[3773591] 'applied index is now lower than readState.Index' (duration: 189.613µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:38.5158Z","caller":"traceutil/trace.go:171","msg":"trace[824094834] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"205.863624ms","start":"2026-04-07T01:33:38.309901Z","end":"2026-04-07T01:33:38.515765Z","steps":["trace[824094834] 'process raft request' (duration: 205.62551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:38.515973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.115365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-07T01:33:38.516023Z","caller":"traceutil/trace.go:171","msg":"trace[594339220] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5497; }","duration":"138.217578ms","start":"2026-04-07T01:33:38.37779Z","end":"2026-04-07T01:33:38.516008Z","steps":["trace[594339220] 'agreement among raft nodes before linearized reading' (duration: 138.069725ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:35:00.80319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3038} {"level":"info","ts":"2026-04-07T01:35:00.882343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3038,"took":"77.048447ms","hash":2372550936,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":32129024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:35:00.882421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2372550936,"revision":3038,"compact-revision":-1} {"level":"info","ts":"2026-04-07T01:39:12.349813Z","caller":"traceutil/trace.go:171","msg":"trace[1356996145] linearizableReadLoop","detail":"{readStateIndex:8344; appliedIndex:8343; }","duration":"210.021166ms","start":"2026-04-07T01:39:12.139775Z","end":"2026-04-07T01:39:12.349796Z","steps":["trace[1356996145] 'read index received' (duration: 209.884443ms)","trace[1356996145] 'applied index is now lower than readState.Index' (duration: 136.103µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:39:12.349951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.179249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T01:39:12.349975Z","caller":"traceutil/trace.go:171","msg":"trace[2026504818] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8065; }","duration":"210.258871ms","start":"2026-04-07T01:39:12.139709Z","end":"2026-04-07T01:39:12.349968Z","steps":["trace[2026504818] 'agreement among raft nodes before linearized reading' (duration: 210.166229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:12.349992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.481888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-66cc74cfb7-xsdjs.18a3ef701f68bb22\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-07T01:39:12.350027Z","caller":"traceutil/trace.go:171","msg":"trace[830924895] range","detail":"{range_begin:/registry/events/openstack/barbican-api-66cc74cfb7-xsdjs.18a3ef701f68bb22; range_end:; response_count:1; response_revision:8065; }","duration":"176.550029ms","start":"2026-04-07T01:39:12.173468Z","end":"2026-04-07T01:39:12.350018Z","steps":["trace[830924895] 'agreement among raft nodes before linearized reading' (duration: 176.458277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:12.350147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.298724ms","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-07T01:39:12.349885Z","caller":"traceutil/trace.go:171","msg":"trace[333809061] transaction","detail":"{read_only:false; response_revision:8065; number_of_response:1; }","duration":"211.861232ms","start":"2026-04-07T01:39:12.138004Z","end":"2026-04-07T01:39:12.349866Z","steps":["trace[333809061] 'process raft request' (duration: 211.696328ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:12.350225Z","caller":"traceutil/trace.go:171","msg":"trace[683615660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8065; }","duration":"105.417256ms","start":"2026-04-07T01:39:12.244786Z","end":"2026-04-07T01:39:12.350203Z","steps":["trace[683615660] 'agreement among raft nodes before linearized reading' (duration: 105.228463ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:12.529171Z","caller":"traceutil/trace.go:171","msg":"trace[201166815] transaction","detail":"{read_only:false; response_revision:8067; number_of_response:1; }","duration":"175.152894ms","start":"2026-04-07T01:39:12.353992Z","end":"2026-04-07T01:39:12.529145Z","steps":["trace[201166815] 'process raft request' (duration: 174.868148ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:12.529425Z","caller":"traceutil/trace.go:171","msg":"trace[363523448] transaction","detail":"{read_only:false; response_revision:8068; number_of_response:1; }","duration":"174.796068ms","start":"2026-04-07T01:39:12.354597Z","end":"2026-04-07T01:39:12.529393Z","steps":["trace[363523448] 'process raft request' (duration: 174.499172ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:12.529429Z","caller":"traceutil/trace.go:171","msg":"trace[677081602] linearizableReadLoop","detail":"{readStateIndex:8347; appliedIndex:8345; }","duration":"139.373952ms","start":"2026-04-07T01:39:12.38996Z","end":"2026-04-07T01:39:12.529334Z","steps":["trace[677081602] 'read index received' (duration: 138.880292ms)","trace[677081602] 'applied index is now lower than readState.Index' (duration: 491.78µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:39:12.529724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.7244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T01:39:12.529775Z","caller":"traceutil/trace.go:171","msg":"trace[3565281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8068; }","duration":"139.863632ms","start":"2026-04-07T01:39:12.389894Z","end":"2026-04-07T01:39:12.529758Z","steps":["trace[3565281] 'agreement among raft nodes before linearized reading' (duration: 139.605508ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:24.844737Z","caller":"traceutil/trace.go:171","msg":"trace[1626848950] transaction","detail":"{read_only:false; response_revision:8132; number_of_response:1; }","duration":"257.040659ms","start":"2026-04-07T01:39:24.587674Z","end":"2026-04-07T01:39:24.844715Z","steps":["trace[1626848950] 'process raft request' (duration: 256.894196ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:24.911775Z","caller":"traceutil/trace.go:171","msg":"trace[885716603] linearizableReadLoop","detail":"{readStateIndex:8416; appliedIndex:8414; }","duration":"263.848116ms","start":"2026-04-07T01:39:24.64789Z","end":"2026-04-07T01:39:24.911739Z","steps":["trace[885716603] 'read index received' (duration: 196.675587ms)","trace[885716603] 'applied index is now lower than readState.Index' (duration: 67.171219ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:24.91191Z","caller":"traceutil/trace.go:171","msg":"trace[739277707] transaction","detail":"{read_only:false; response_revision:8133; number_of_response:1; }","duration":"324.173288ms","start":"2026-04-07T01:39:24.587693Z","end":"2026-04-07T01:39:24.911867Z","steps":["trace[739277707] 'process raft request' (duration: 290.870323ms)","trace[739277707] 'compare' (duration: 32.956118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:39:24.912093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:24.587672Z","time spent":"324.33016ms","remote":"127.0.0.1:60172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T01:39:24.912164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.231223ms","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-04-07T01:39:24.912213Z","caller":"traceutil/trace.go:171","msg":"trace[1050272364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8133; }","duration":"264.341955ms","start":"2026-04-07T01:39:24.647858Z","end":"2026-04-07T01:39:24.912199Z","steps":["trace[1050272364] 'agreement among raft nodes before linearized reading' (duration: 264.07777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:24.912336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.558107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-07T01:39:24.912422Z","caller":"traceutil/trace.go:171","msg":"trace[1046094160] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8133; }","duration":"131.693159ms","start":"2026-04-07T01:39:24.780709Z","end":"2026-04-07T01:39:24.912402Z","steps":["trace[1046094160] 'agreement among raft nodes before linearized reading' (duration: 131.431095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:52.263332Z","caller":"traceutil/trace.go:171","msg":"trace[702646431] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"120.34322ms","start":"2026-04-07T01:39:52.142917Z","end":"2026-04-07T01:39:52.26326Z","steps":["trace[702646431] 'process raft request' (duration: 120.083685ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:00.810955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6180} {"level":"info","ts":"2026-04-07T01:40:00.910555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6180,"took":"97.610124ms","hash":1947103829,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":23302144,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-07T01:40:00.910701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1947103829,"revision":6180,"compact-revision":3038} {"level":"warn","ts":"2026-04-07T01:40:05.028765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.679492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T01:40:05.028865Z","caller":"traceutil/trace.go:171","msg":"trace[1204655093] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8439; }","duration":"221.201443ms","start":"2026-04-07T01:40:04.807648Z","end":"2026-04-07T01:40:05.028849Z","steps":["trace[1204655093] 'process raft request' (duration: 105.313468ms)","trace[1204655093] 'compare' (duration: 115.413327ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:05.030394Z","caller":"traceutil/trace.go:171","msg":"trace[486802364] linearizableReadLoop","detail":"{readStateIndex:8737; appliedIndex:8735; }","duration":"179.144424ms","start":"2026-04-07T01:40:04.851208Z","end":"2026-04-07T01:40:05.030352Z","steps":["trace[486802364] 'read index received' (duration: 61.76442ms)","trace[486802364] 'applied index is now lower than readState.Index' (duration: 117.378724ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:05.030424Z","caller":"traceutil/trace.go:171","msg":"trace[2066753095] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"219.037223ms","start":"2026-04-07T01:40:04.811366Z","end":"2026-04-07T01:40:05.030403Z","steps":["trace[2066753095] 'process raft request' (duration: 218.84738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:05.030519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.291057ms","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-04-07T01:40:05.030561Z","caller":"traceutil/trace.go:171","msg":"trace[1571141015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8440; }","duration":"179.388609ms","start":"2026-04-07T01:40:04.851159Z","end":"2026-04-07T01:40:05.030548Z","steps":["trace[1571141015] 'agreement among raft nodes before linearized reading' (duration: 179.302347ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:05.902214Z","caller":"traceutil/trace.go:171","msg":"trace[940471939] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"132.904785ms","start":"2026-04-07T01:40:05.769259Z","end":"2026-04-07T01:40:05.902164Z","steps":["trace[940471939] 'process raft request' (duration: 68.74702ms)","trace[940471939] 'compare' (duration: 63.858619ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:06.487716Z","caller":"traceutil/trace.go:171","msg":"trace[1790277574] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"103.707857ms","start":"2026-04-07T01:40:06.383975Z","end":"2026-04-07T01:40:06.487683Z","steps":["trace[1790277574] 'process raft request' (duration: 69.897723ms)","trace[1790277574] 'compare' (duration: 33.626111ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:41:04.924546Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249f.wal"} {"level":"info","ts":"2026-04-07T01:42:27.902941Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e3aaa5c123e150e0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:42:27.911836Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T01:42:27.911959Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T01:43:10.27579Z","caller":"traceutil/trace.go:171","msg":"trace[431249238] transaction","detail":"{read_only:false; response_revision:9980; number_of_response:1; }","duration":"101.075906ms","start":"2026-04-07T01:43:10.174679Z","end":"2026-04-07T01:43:10.275755Z","steps":["trace[431249238] 'process raft request' (duration: 39.279747ms)","trace[431249238] 'compare' (duration: 61.657166ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:45:00.819808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8408} {"level":"info","ts":"2026-04-07T01:45:00.897282Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8408,"took":"75.207129ms","hash":4082865873,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":24522752,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-07T01:45:00.897366Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4082865873,"revision":8408,"compact-revision":6180} {"level":"info","ts":"2026-04-07T01:50:00.826098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10722} {"level":"info","ts":"2026-04-07T01:50:00.898689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10722,"took":"70.568903ms","hash":2861350555,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":20340736,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T01:50:00.898767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2861350555,"revision":10722,"compact-revision":8408}