{"level":"warn","ts":"2026-04-20T04:45:55.424033Z","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-20T04:45:55.424117Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-20T04:45:55.424276Z","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-20T04:45:55.424289Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-20T04:45:55.42435Z","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-20T04:45:55.425258Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-04-20T04:45:55.425413Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-20T04:45:55.429054Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.308004ms"} {"level":"info","ts":"2026-04-20T04:45:55.434163Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-04-20T04:45:55.434273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T04:45:55.434312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-04-20T04:45:55.434351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T04:45:55.43438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-04-20T04:45:55.434434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-04-20T04:45:55.439854Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T04:45:55.441325Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T04:45:55.442356Z","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-20T04:45:55.444583Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T04:45:55.444827Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T04:45:55.44515Z","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-20T04:45:55.445233Z","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-20T04:45:55.445249Z","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-20T04:45:55.446339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-04-20T04:45:55.446859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-04-20T04:45:55.451803Z","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-20T04:45:55.451974Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-20T04:45:55.452029Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-04-20T04:45:55.452189Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T04:45:55.452288Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T04:45:56.335577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T04:45:56.335653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T04:45:56.335682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-04-20T04:45:56.335703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-04-20T04:45:56.335715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-20T04:45:56.335732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-04-20T04:45:56.335745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-04-20T04:45:56.336655Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T04:45:56.336985Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:45:56.337209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:45:56.337334Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T04:45:56.337406Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T04:45:56.337681Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:45:56.337957Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:45:56.338095Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:45:56.338131Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:45:56.339627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-04-20T04:45:56.341158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T04:46:06.437597Z","caller":"traceutil/trace.go:171","msg":"trace[510434434] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"161.525285ms","start":"2026-04-20T04:46:06.276041Z","end":"2026-04-20T04:46:06.437566Z","steps":["trace[510434434] 'read index received' (duration: 161.232213ms)","trace[510434434] 'applied index is now lower than readState.Index' (duration: 291.082µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:46:06.437817Z","caller":"traceutil/trace.go:171","msg":"trace[233056821] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"164.067957ms","start":"2026-04-20T04:46:06.273715Z","end":"2026-04-20T04:46:06.437783Z","steps":["trace[233056821] 'process raft request' (duration: 163.664994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:06.437862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.760907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T04:46:06.43795Z","caller":"traceutil/trace.go:171","msg":"trace[458730080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:350; }","duration":"161.93229ms","start":"2026-04-20T04:46:06.276003Z","end":"2026-04-20T04:46:06.437936Z","steps":["trace[458730080] 'agreement among raft nodes before linearized reading' (duration: 161.710818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:06.859242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.604302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:46:06.859382Z","caller":"traceutil/trace.go:171","msg":"trace[1295095133] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"418.405453ms","start":"2026-04-20T04:46:06.440964Z","end":"2026-04-20T04:46:06.85937Z","steps":["trace[1295095133] 'process raft request' (duration: 295.344847ms)","trace[1295095133] 'compare' (duration: 122.468131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:46:06.859424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:46:06.440947Z","time spent":"418.459774ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:46:26.309673Z","caller":"traceutil/trace.go:171","msg":"trace[2079651073] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:560; }","duration":"148.314856ms","start":"2026-04-20T04:46:26.161339Z","end":"2026-04-20T04:46:26.309654Z","steps":["trace[2079651073] 'read index received' (duration: 148.152685ms)","trace[2079651073] 'applied index is now lower than readState.Index' (duration: 161.451µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:46:26.30976Z","caller":"traceutil/trace.go:171","msg":"trace[899873682] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"161.659713ms","start":"2026-04-20T04:46:26.148054Z","end":"2026-04-20T04:46:26.309714Z","steps":["trace[899873682] 'process raft request' (duration: 161.441652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:26.309879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.522047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:208485"} {"level":"info","ts":"2026-04-20T04:46:26.309901Z","caller":"traceutil/trace.go:171","msg":"trace[936298060] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/securitypolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:542; }","duration":"148.605848ms","start":"2026-04-20T04:46:26.161288Z","end":"2026-04-20T04:46:26.309894Z","steps":["trace[936298060] 'agreement among raft nodes before linearized reading' (duration: 148.435637ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:46:31.349417Z","caller":"traceutil/trace.go:171","msg":"trace[188907967] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"319.848027ms","start":"2026-04-20T04:46:31.029536Z","end":"2026-04-20T04:46:31.349384Z","steps":["trace[188907967] 'read index received' (duration: 319.543875ms)","trace[188907967] 'applied index is now lower than readState.Index' (duration: 302.182µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:46:31.349603Z","caller":"traceutil/trace.go:171","msg":"trace[1781723973] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"517.439119ms","start":"2026-04-20T04:46:30.832133Z","end":"2026-04-20T04:46:31.349572Z","steps":["trace[1781723973] 'process raft request' (duration: 517.089396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:31.349674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.122739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T04:46:31.349721Z","caller":"traceutil/trace.go:171","msg":"trace[380002822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"320.217759ms","start":"2026-04-20T04:46:31.029487Z","end":"2026-04-20T04:46:31.349705Z","steps":["trace[380002822] 'agreement among raft nodes before linearized reading' (duration: 320.095089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:31.349725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:46:30.832116Z","time spent":"517.550089ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:46:31.349758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:46:31.029446Z","time spent":"320.30288ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T04:46:31.350166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.235604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nw6t6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T04:46:31.35021Z","caller":"traceutil/trace.go:171","msg":"trace[568221925] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nw6t6; range_end:; response_count:1; response_revision:572; }","duration":"222.319555ms","start":"2026-04-20T04:46:31.127878Z","end":"2026-04-20T04:46:31.350197Z","steps":["trace[568221925] 'agreement among raft nodes before linearized reading' (duration: 222.137844ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:40.625946Z","caller":"traceutil/trace.go:171","msg":"trace[656122847] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"110.397538ms","start":"2026-04-20T04:47:40.515527Z","end":"2026-04-20T04:47:40.625924Z","steps":["trace[656122847] 'process raft request' (duration: 110.04824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:03.26933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.680161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:48:03.269536Z","caller":"traceutil/trace.go:171","msg":"trace[951431375] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"286.645723ms","start":"2026-04-20T04:48:02.982867Z","end":"2026-04-20T04:48:03.269513Z","steps":["trace[951431375] 'process raft request' (duration: 20.669695ms)","trace[951431375] 'compare' (duration: 265.507667ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:03.293909Z","caller":"traceutil/trace.go:171","msg":"trace[1627950187] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"199.413465ms","start":"2026-04-20T04:48:03.094463Z","end":"2026-04-20T04:48:03.293876Z","steps":["trace[1627950187] 'read index received' (duration: 199.382904ms)","trace[1627950187] 'applied index is now lower than readState.Index' (duration: 29.471µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:03.293982Z","caller":"traceutil/trace.go:171","msg":"trace[1988878918] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"299.347388ms","start":"2026-04-20T04:48:02.994612Z","end":"2026-04-20T04:48:03.293959Z","steps":["trace[1988878918] 'process raft request' (duration: 299.147284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:03.294135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.671209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-20T04:48:03.294173Z","caller":"traceutil/trace.go:171","msg":"trace[1600597447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1796; }","duration":"199.788592ms","start":"2026-04-20T04:48:03.094375Z","end":"2026-04-20T04:48:03.294164Z","steps":["trace[1600597447] 'agreement among raft nodes before linearized reading' (duration: 199.636459ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:48:03.4469Z","caller":"traceutil/trace.go:171","msg":"trace[1915851574] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"208.384598ms","start":"2026-04-20T04:48:03.238488Z","end":"2026-04-20T04:48:03.446873Z","steps":["trace[1915851574] 'process raft request' (duration: 170.564448ms)","trace[1915851574] 'compare' (duration: 37.618656ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:03.447089Z","caller":"traceutil/trace.go:171","msg":"trace[1757750008] linearizableReadLoop","detail":"{readStateIndex:1865; appliedIndex:1864; }","duration":"153.090503ms","start":"2026-04-20T04:48:03.293988Z","end":"2026-04-20T04:48:03.447079Z","steps":["trace[1757750008] 'read index received' (duration: 115.041919ms)","trace[1757750008] 'applied index is now lower than readState.Index' (duration: 38.047494ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:03.447153Z","caller":"traceutil/trace.go:171","msg":"trace[2005954866] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"148.646782ms","start":"2026-04-20T04:48:03.298499Z","end":"2026-04-20T04:48:03.447145Z","steps":["trace[2005954866] 'process raft request' (duration: 148.306806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:03.447535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.651146ms","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-20T04:48:03.447603Z","caller":"traceutil/trace.go:171","msg":"trace[1170884126] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1798; }","duration":"187.763048ms","start":"2026-04-20T04:48:03.259818Z","end":"2026-04-20T04:48:03.447581Z","steps":["trace[1170884126] 'agreement among raft nodes before linearized reading' (duration: 187.293889ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:48:03.702406Z","caller":"traceutil/trace.go:171","msg":"trace[1568832370] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1866; }","duration":"149.291145ms","start":"2026-04-20T04:48:03.553087Z","end":"2026-04-20T04:48:03.702378Z","steps":["trace[1568832370] 'read index received' (duration: 147.926858ms)","trace[1568832370] 'applied index is now lower than readState.Index' (duration: 1.362727ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:03.702595Z","caller":"traceutil/trace.go:171","msg":"trace[303687176] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"158.975043ms","start":"2026-04-20T04:48:03.543599Z","end":"2026-04-20T04:48:03.702574Z","steps":["trace[303687176] 'process raft request' (duration: 157.484723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:03.702668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.561621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5f6bf962-7a90-4dcb-b331-24816736734d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T04:48:03.702748Z","caller":"traceutil/trace.go:171","msg":"trace[1018573770] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5f6bf962-7a90-4dcb-b331-24816736734d; range_end:; response_count:1; response_revision:1799; }","duration":"149.682803ms","start":"2026-04-20T04:48:03.553047Z","end":"2026-04-20T04:48:03.70273Z","steps":["trace[1018573770] 'agreement among raft nodes before linearized reading' (duration: 149.479689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:03.702737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.624514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5f6bf962-7a90-4dcb-b331-24816736734d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T04:48:03.702808Z","caller":"traceutil/trace.go:171","msg":"trace[118168517] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5f6bf962-7a90-4dcb-b331-24816736734d; range_end:; response_count:1; response_revision:1799; }","duration":"127.749507ms","start":"2026-04-20T04:48:03.57504Z","end":"2026-04-20T04:48:03.702789Z","steps":["trace[118168517] 'agreement among raft nodes before linearized reading' (duration: 127.522313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:37.786432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.709966ms","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-20T04:48:37.786553Z","caller":"traceutil/trace.go:171","msg":"trace[884913712] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2041; }","duration":"109.857538ms","start":"2026-04-20T04:48:37.676666Z","end":"2026-04-20T04:48:37.786523Z","steps":["trace[884913712] 'range keys from in-memory index tree' (duration: 109.472302ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:48:38.247563Z","caller":"traceutil/trace.go:171","msg":"trace[600545466] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2118; }","duration":"153.448453ms","start":"2026-04-20T04:48:38.094091Z","end":"2026-04-20T04:48:38.247539Z","steps":["trace[600545466] 'read index received' (duration: 152.896294ms)","trace[600545466] 'applied index is now lower than readState.Index' (duration: 550.989µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:38.247641Z","caller":"traceutil/trace.go:171","msg":"trace[1033708484] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"240.623623ms","start":"2026-04-20T04:48:38.006989Z","end":"2026-04-20T04:48:38.247612Z","steps":["trace[1033708484] 'process raft request' (duration: 240.035473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:38.247761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.671106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T04:48:38.2478Z","caller":"traceutil/trace.go:171","msg":"trace[322972189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2042; }","duration":"153.759488ms","start":"2026-04-20T04:48:38.094027Z","end":"2026-04-20T04:48:38.247786Z","steps":["trace[322972189] 'agreement among raft nodes before linearized reading' (duration: 153.609006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:38.477583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.434966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:48:38.477987Z","caller":"traceutil/trace.go:171","msg":"trace[1690682105] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"199.477226ms","start":"2026-04-20T04:48:38.278457Z","end":"2026-04-20T04:48:38.477934Z","steps":["trace[1690682105] 'process raft request' (duration: 39.602893ms)","trace[1690682105] 'compare' (duration: 159.188553ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:38.478201Z","caller":"traceutil/trace.go:171","msg":"trace[33301118] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"177.708424ms","start":"2026-04-20T04:48:38.300463Z","end":"2026-04-20T04:48:38.478172Z","steps":["trace[33301118] 'process raft request' (duration: 177.347308ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:49:41.794829Z","caller":"traceutil/trace.go:171","msg":"trace[268281929] linearizableReadLoop","detail":"{readStateIndex:2582; appliedIndex:2581; }","duration":"188.752863ms","start":"2026-04-20T04:49:41.606058Z","end":"2026-04-20T04:49:41.794811Z","steps":["trace[268281929] 'read index received' (duration: 188.681042ms)","trace[268281929] 'applied index is now lower than readState.Index' (duration: 71.291µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:49:41.795041Z","caller":"traceutil/trace.go:171","msg":"trace[1616066631] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"196.642007ms","start":"2026-04-20T04:49:41.598391Z","end":"2026-04-20T04:49:41.795033Z","steps":["trace[1616066631] 'process raft request' (duration: 196.225482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:49:41.795168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.103107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:49:41.795188Z","caller":"traceutil/trace.go:171","msg":"trace[548498674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2490; }","duration":"189.162927ms","start":"2026-04-20T04:49:41.606019Z","end":"2026-04-20T04:49:41.795182Z","steps":["trace[548498674] 'agreement among raft nodes before linearized reading' (duration: 189.119257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:15.002061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.563781ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T04:50:15.002328Z","caller":"traceutil/trace.go:171","msg":"trace[1186063690] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"196.138525ms","start":"2026-04-20T04:50:14.806168Z","end":"2026-04-20T04:50:15.002307Z","steps":["trace[1186063690] 'process raft request' (duration: 196.000494ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:15.002349Z","caller":"traceutil/trace.go:171","msg":"trace[353184235] linearizableReadLoop","detail":"{readStateIndex:2772; appliedIndex:2771; }","duration":"221.350129ms","start":"2026-04-20T04:50:14.78094Z","end":"2026-04-20T04:50:15.00229Z","steps":["trace[353184235] 'read index received' (duration: 67.811µs)","trace[353184235] 'applied index is now lower than readState.Index' (duration: 221.277858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:50:15.002973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.003547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-20T04:50:15.003022Z","caller":"traceutil/trace.go:171","msg":"trace[664449511] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2673; }","duration":"222.145598ms","start":"2026-04-20T04:50:14.780866Z","end":"2026-04-20T04:50:15.003011Z","steps":["trace[664449511] 'agreement among raft nodes before linearized reading' (duration: 221.56098ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:15.163711Z","caller":"traceutil/trace.go:171","msg":"trace[40152817] linearizableReadLoop","detail":"{readStateIndex:2774; appliedIndex:2773; }","duration":"106.967557ms","start":"2026-04-20T04:50:15.056707Z","end":"2026-04-20T04:50:15.163674Z","steps":["trace[40152817] 'read index received' (duration: 94.107527ms)","trace[40152817] 'applied index is now lower than readState.Index' (duration: 12.85866ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:50:15.163789Z","caller":"traceutil/trace.go:171","msg":"trace[1230344738] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"139.64356ms","start":"2026-04-20T04:50:15.024105Z","end":"2026-04-20T04:50:15.163749Z","steps":["trace[1230344738] 'process raft request' (duration: 126.512638ms)","trace[1230344738] 'compare' (duration: 12.90966ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:50:15.164165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.398641ms","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-20T04:50:15.164563Z","caller":"traceutil/trace.go:171","msg":"trace[1911401403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2674; }","duration":"107.878917ms","start":"2026-04-20T04:50:15.056648Z","end":"2026-04-20T04:50:15.164527Z","steps":["trace[1911401403] 'agreement among raft nodes before linearized reading' (duration: 107.179429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:15.189567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.513857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a7f764be99ec06\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-20T04:50:15.189676Z","caller":"traceutil/trace.go:171","msg":"trace[1718548527] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a7f764be99ec06; range_end:; response_count:1; response_revision:2675; }","duration":"100.658458ms","start":"2026-04-20T04:50:15.088984Z","end":"2026-04-20T04:50:15.189643Z","steps":["trace[1718548527] 'agreement among raft nodes before linearized reading' (duration: 100.356595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:15.444763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.141594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:50:15.445264Z","caller":"traceutil/trace.go:171","msg":"trace[68973249] linearizableReadLoop","detail":"{readStateIndex:2776; appliedIndex:2775; }","duration":"252.256088ms","start":"2026-04-20T04:50:15.192991Z","end":"2026-04-20T04:50:15.445247Z","steps":["trace[68973249] 'read index received' (duration: 112.546418ms)","trace[68973249] 'applied index is now lower than readState.Index' (duration: 139.70822ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:50:15.445334Z","caller":"traceutil/trace.go:171","msg":"trace[1807418191] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"275.114085ms","start":"2026-04-20T04:50:15.170207Z","end":"2026-04-20T04:50:15.445321Z","steps":["trace[1807418191] 'process raft request' (duration: 135.400005ms)","trace[1807418191] 'compare' (duration: 138.679959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:50:15.445484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.44938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:50:15.445658Z","caller":"traceutil/trace.go:171","msg":"trace[527842507] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2676; }","duration":"252.640113ms","start":"2026-04-20T04:50:15.192953Z","end":"2026-04-20T04:50:15.445593Z","steps":["trace[527842507] 'agreement among raft nodes before linearized reading' (duration: 252.434289ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:15.45215Z","caller":"traceutil/trace.go:171","msg":"trace[1487061824] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"259.019501ms","start":"2026-04-20T04:50:15.193112Z","end":"2026-04-20T04:50:15.452131Z","steps":["trace[1487061824] 'process raft request' (duration: 258.839109ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:49.64005Z","caller":"traceutil/trace.go:171","msg":"trace[73751346] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"105.594684ms","start":"2026-04-20T04:53:49.534427Z","end":"2026-04-20T04:53:49.640021Z","steps":["trace[73751346] 'process raft request' (duration: 92.003231ms)","trace[73751346] 'compare' (duration: 12.798278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:49.64389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.490733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-20T04:53:49.643992Z","caller":"traceutil/trace.go:171","msg":"trace[1975052998] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4489; }","duration":"102.635787ms","start":"2026-04-20T04:53:49.541335Z","end":"2026-04-20T04:53:49.643971Z","steps":["trace[1975052998] 'agreement among raft nodes before linearized reading' (duration: 98.627738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:55.926749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.787754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:55.9269Z","caller":"traceutil/trace.go:171","msg":"trace[1072474112] linearizableReadLoop","detail":"{readStateIndex:4728; appliedIndex:4727; }","duration":"183.244528ms","start":"2026-04-20T04:53:55.743636Z","end":"2026-04-20T04:53:55.92688Z","steps":["trace[1072474112] 'read index received' (duration: 56.270821ms)","trace[1072474112] 'applied index is now lower than readState.Index' (duration: 126.972187ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:55.927073Z","caller":"traceutil/trace.go:171","msg":"trace[634902423] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"282.243243ms","start":"2026-04-20T04:53:55.644816Z","end":"2026-04-20T04:53:55.927059Z","steps":["trace[634902423] 'process raft request' (duration: 155.057152ms)","trace[634902423] 'compare' (duration: 126.280454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:55.927488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.84607ms","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-20T04:53:55.927528Z","caller":"traceutil/trace.go:171","msg":"trace[1045064323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4554; }","duration":"183.951802ms","start":"2026-04-20T04:53:55.743566Z","end":"2026-04-20T04:53:55.927518Z","steps":["trace[1045064323] 'agreement among raft nodes before linearized reading' (duration: 183.692406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:55.933908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.120659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T04:53:55.934005Z","caller":"traceutil/trace.go:171","msg":"trace[1406105795] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4555; }","duration":"149.293902ms","start":"2026-04-20T04:53:55.784691Z","end":"2026-04-20T04:53:55.933985Z","steps":["trace[1406105795] 'agreement among raft nodes before linearized reading' (duration: 149.012877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:56.407122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.850521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:56.407269Z","caller":"traceutil/trace.go:171","msg":"trace[929309811] linearizableReadLoop","detail":"{readStateIndex:4737; appliedIndex:4736; }","duration":"149.485789ms","start":"2026-04-20T04:53:56.257763Z","end":"2026-04-20T04:53:56.407249Z","steps":["trace[929309811] 'read index received' (duration: 14.423665ms)","trace[929309811] 'applied index is now lower than readState.Index' (duration: 135.060744ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:56.407356Z","caller":"traceutil/trace.go:171","msg":"trace[1019480576] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"149.886507ms","start":"2026-04-20T04:53:56.257454Z","end":"2026-04-20T04:53:56.407341Z","steps":["trace[1019480576] 'process raft request' (duration: 14.726241ms)","trace[1019480576] 'compare' (duration: 134.686807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:56.407694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.904357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:118953"} {"level":"info","ts":"2026-04-20T04:53:56.407794Z","caller":"traceutil/trace.go:171","msg":"trace[189516525] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4563; }","duration":"150.05172ms","start":"2026-04-20T04:53:56.257721Z","end":"2026-04-20T04:53:56.407773Z","steps":["trace[189516525] 'agreement among raft nodes before linearized reading' (duration: 149.678403ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:56.451445Z","caller":"traceutil/trace.go:171","msg":"trace[606926488] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"193.141238ms","start":"2026-04-20T04:53:56.258273Z","end":"2026-04-20T04:53:56.451414Z","steps":["trace[606926488] 'process raft request' (duration: 192.984715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:56.451731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.979567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-04-20T04:53:56.451832Z","caller":"traceutil/trace.go:171","msg":"trace[208381533] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4566; }","duration":"190.131461ms","start":"2026-04-20T04:53:56.261654Z","end":"2026-04-20T04:53:56.451785Z","steps":["trace[208381533] 'agreement among raft nodes before linearized reading' (duration: 189.798754ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:56.451886Z","caller":"traceutil/trace.go:171","msg":"trace[1023545415] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"193.541454ms","start":"2026-04-20T04:53:56.258336Z","end":"2026-04-20T04:53:56.451877Z","steps":["trace[1023545415] 'process raft request' (duration: 193.015815ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:56.451844Z","caller":"traceutil/trace.go:171","msg":"trace[595747707] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"193.450713ms","start":"2026-04-20T04:53:56.258376Z","end":"2026-04-20T04:53:56.451827Z","steps":["trace[595747707] 'process raft request' (duration: 193.023125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:56.452214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.448879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-04-20T04:53:56.452296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.49817ms","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-20T04:53:56.452357Z","caller":"traceutil/trace.go:171","msg":"trace[1670769664] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4566; }","duration":"108.599502ms","start":"2026-04-20T04:53:56.343702Z","end":"2026-04-20T04:53:56.452301Z","steps":["trace[1670769664] 'agreement among raft nodes before linearized reading' (duration: 108.233745ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:56.452387Z","caller":"traceutil/trace.go:171","msg":"trace[1506826764] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4566; }","duration":"128.596482ms","start":"2026-04-20T04:53:56.323746Z","end":"2026-04-20T04:53:56.452343Z","steps":["trace[1506826764] 'agreement among raft nodes before linearized reading' (duration: 128.427939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:08.333518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.630509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:54:08.333633Z","caller":"traceutil/trace.go:171","msg":"trace[1319798566] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"314.965104ms","start":"2026-04-20T04:54:08.018649Z","end":"2026-04-20T04:54:08.333614Z","steps":["trace[1319798566] 'process raft request' (duration: 206.160272ms)","trace[1319798566] 'compare' (duration: 108.526327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:08.333693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:08.018629Z","time spent":"315.036036ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:08.343375Z","caller":"traceutil/trace.go:171","msg":"trace[95537651] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"308.484546ms","start":"2026-04-20T04:54:08.03487Z","end":"2026-04-20T04:54:08.343354Z","steps":["trace[95537651] 'process raft request' (duration: 308.086228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:08.343535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:08.034847Z","time spent":"308.601379ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:08.343724Z","caller":"traceutil/trace.go:171","msg":"trace[1752927582] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4963; }","duration":"301.432336ms","start":"2026-04-20T04:54:08.041673Z","end":"2026-04-20T04:54:08.343105Z","steps":["trace[1752927582] 'read index received' (duration: 183.147999ms)","trace[1752927582] 'applied index is now lower than readState.Index' (duration: 118.283387ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:08.343843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.133739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T04:54:08.343931Z","caller":"traceutil/trace.go:171","msg":"trace[1194065947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4785; }","duration":"302.277012ms","start":"2026-04-20T04:54:08.041634Z","end":"2026-04-20T04:54:08.343911Z","steps":["trace[1194065947] 'agreement among raft nodes before linearized reading' (duration: 301.946466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:08.343995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:08.041621Z","time spent":"302.363613ms","remote":"127.0.0.1:36092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T04:54:08.344469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.476382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-5p4t5.18a7f79c698485ec\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-20T04:54:08.344517Z","caller":"traceutil/trace.go:171","msg":"trace[1454280554] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-5p4t5.18a7f79c698485ec; range_end:; response_count:1; response_revision:4785; }","duration":"298.548554ms","start":"2026-04-20T04:54:08.045955Z","end":"2026-04-20T04:54:08.344504Z","steps":["trace[1454280554] 'agreement among raft nodes before linearized reading' (duration: 297.895042ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:48.276671Z","caller":"traceutil/trace.go:171","msg":"trace[1525349612] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"101.940522ms","start":"2026-04-20T04:54:48.174693Z","end":"2026-04-20T04:54:48.276633Z","steps":["trace[1525349612] 'process raft request' (duration: 44.532908ms)","trace[1525349612] 'compare' (duration: 57.131699ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:00.265963Z","caller":"traceutil/trace.go:171","msg":"trace[791547194] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"107.639145ms","start":"2026-04-20T04:55:00.158297Z","end":"2026-04-20T04:55:00.265936Z","steps":["trace[791547194] 'process raft request' (duration: 107.398071ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:56.364859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-04-20T04:55:56.457498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"90.829931ms","hash":1758152798,"current-db-size-bytes":52404224,"current-db-size":"52 MB","current-db-size-in-use-bytes":29077504,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T04:55:56.457586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1758152798,"revision":2892,"compact-revision":-1} {"level":"info","ts":"2026-04-20T05:00:56.371294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5609} {"level":"info","ts":"2026-04-20T05:00:56.448934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5609,"took":"76.328489ms","hash":1543049052,"current-db-size-bytes":52404224,"current-db-size":"52 MB","current-db-size-in-use-bytes":18026496,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T05:00:56.449017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1543049052,"revision":5609,"compact-revision":2892}