{"level":"warn","ts":"2026-03-02T02:08:29.922024Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:08:29.922152Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-03-02T02:08:29.922253Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T02:08:29.92227Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-03-02T02:08:29.922313Z","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-03-02T02:08:29.923387Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-03-02T02:08:29.923496Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-03-02T02:08:29.927987Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.145701ms"} {"level":"info","ts":"2026-03-02T02:08:29.933318Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-03-02T02:08:29.933385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T02:08:29.933401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-03-02T02:08:29.933414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T02:08:29.933424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-03-02T02:08:29.933469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-03-02T02:08:29.939925Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T02:08:29.941383Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T02:08:29.942471Z","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-03-02T02:08:29.945176Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T02:08:29.945368Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T02:08:29.945439Z","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-03-02T02:08:29.945574Z","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-03-02T02:08:29.945593Z","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-03-02T02:08:29.948773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-03-02T02:08:29.948973Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-03-02T02:08:29.950265Z","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-03-02T02:08:29.950339Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-03-02T02:08:29.950402Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-03-02T02:08:29.950616Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T02:08:29.950678Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T02:08:30.634122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T02:08:30.634171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T02:08:30.634208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-03-02T02:08:30.634222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-03-02T02:08:30.634229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-03-02T02:08:30.63424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-03-02T02:08:30.63425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-03-02T02:08:30.635378Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T02:08:30.635395Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:08:30.635582Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:08:30.63567Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T02:08:30.635712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T02:08:30.635962Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T02:08:30.636552Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:08:30.636639Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:08:30.636669Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T02:08:30.637632Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T02:08:30.637829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"warn","ts":"2026-03-02T02:08:41.39034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.012797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:08:41.390489Z","caller":"traceutil/trace.go:171","msg":"trace[1512678588] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"247.685ms","start":"2026-03-02T02:08:41.142785Z","end":"2026-03-02T02:08:41.39047Z","steps":["trace[1512678588] 'process raft request' (duration: 116.710248ms)","trace[1512678588] 'compare' (duration: 129.77675ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:41.392045Z","caller":"traceutil/trace.go:171","msg":"trace[1832737917] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:387; }","duration":"164.993027ms","start":"2026-03-02T02:08:41.227035Z","end":"2026-03-02T02:08:41.392028Z","steps":["trace[1832737917] 'read index received' (duration: 32.440862ms)","trace[1832737917] 'applied index is now lower than readState.Index' (duration: 132.551445ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:08:41.3922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.135071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-02T02:08:41.39224Z","caller":"traceutil/trace.go:171","msg":"trace[1607248593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"165.249384ms","start":"2026-03-02T02:08:41.226982Z","end":"2026-03-02T02:08:41.392231Z","steps":["trace[1607248593] 'agreement among raft nodes before linearized reading' (duration: 165.132291ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:41.392234Z","caller":"traceutil/trace.go:171","msg":"trace[1645301190] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"239.032588ms","start":"2026-03-02T02:08:41.153182Z","end":"2026-03-02T02:08:41.392214Z","steps":["trace[1645301190] 'process raft request' (duration: 238.815022ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:41.392197Z","caller":"traceutil/trace.go:171","msg":"trace[1620855459] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"247.047895ms","start":"2026-03-02T02:08:41.145115Z","end":"2026-03-02T02:08:41.392163Z","steps":["trace[1620855459] 'process raft request' (duration: 246.853289ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:41.392388Z","caller":"traceutil/trace.go:171","msg":"trace[1456751176] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"248.949965ms","start":"2026-03-02T02:08:41.143172Z","end":"2026-03-02T02:08:41.392122Z","steps":["trace[1456751176] 'process raft request' (duration: 248.741928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:41.650116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.396165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:08:41.650525Z","caller":"traceutil/trace.go:171","msg":"trace[1535989135] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"252.09645ms","start":"2026-03-02T02:08:41.398395Z","end":"2026-03-02T02:08:41.650492Z","steps":["trace[1535989135] 'process raft request' (duration: 149.020258ms)","trace[1535989135] 'compare' (duration: 102.137546ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:41.650665Z","caller":"traceutil/trace.go:171","msg":"trace[66159413] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"252.12449ms","start":"2026-03-02T02:08:41.398519Z","end":"2026-03-02T02:08:41.650643Z","steps":["trace[66159413] 'process raft request' (duration: 251.728289ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:41.650942Z","caller":"traceutil/trace.go:171","msg":"trace[845551560] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"251.186985ms","start":"2026-03-02T02:08:41.399683Z","end":"2026-03-02T02:08:41.65087Z","steps":["trace[845551560] 'process raft request' (duration: 250.880787ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:41.650977Z","caller":"traceutil/trace.go:171","msg":"trace[990901263] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:392; }","duration":"101.080908ms","start":"2026-03-02T02:08:41.549873Z","end":"2026-03-02T02:08:41.650954Z","steps":["trace[990901263] 'read index received' (duration: 30.061µs)","trace[990901263] 'applied index is now lower than readState.Index' (duration: 101.049477ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:08:41.651044Z","caller":"traceutil/trace.go:171","msg":"trace[612937766] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"250.039974ms","start":"2026-03-02T02:08:41.400981Z","end":"2026-03-02T02:08:41.651021Z","steps":["trace[612937766] 'process raft request' (duration: 249.833648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:08:41.651098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.213751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-02T02:08:41.651136Z","caller":"traceutil/trace.go:171","msg":"trace[1521274416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:385; }","duration":"101.300654ms","start":"2026-03-02T02:08:41.549823Z","end":"2026-03-02T02:08:41.651123Z","steps":["trace[1521274416] 'agreement among raft nodes before linearized reading' (duration: 101.213771ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:41.836976Z","caller":"traceutil/trace.go:171","msg":"trace[1555601115] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"179.137867ms","start":"2026-03-02T02:08:41.657799Z","end":"2026-03-02T02:08:41.836936Z","steps":["trace[1555601115] 'process raft request' (duration: 178.987493ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:08:41.836989Z","caller":"traceutil/trace.go:171","msg":"trace[608509347] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"181.669306ms","start":"2026-03-02T02:08:41.655286Z","end":"2026-03-02T02:08:41.836955Z","steps":["trace[608509347] 'process raft request' (duration: 110.360829ms)","trace[608509347] 'compare' (duration: 70.952948ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:05.849977Z","caller":"traceutil/trace.go:171","msg":"trace[622854349] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"236.460086ms","start":"2026-03-02T02:09:05.613487Z","end":"2026-03-02T02:09:05.849948Z","steps":["trace[622854349] 'process raft request' (duration: 236.304541ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:06.247396Z","caller":"traceutil/trace.go:171","msg":"trace[2119421401] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"282.210316ms","start":"2026-03-02T02:09:05.965162Z","end":"2026-03-02T02:09:06.247372Z","steps":["trace[2119421401] 'process raft request' (duration: 250.904629ms)","trace[2119421401] 'compare' (duration: 31.228055ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:06.404555Z","caller":"traceutil/trace.go:171","msg":"trace[415705274] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"356.032499ms","start":"2026-03-02T02:09:06.048497Z","end":"2026-03-02T02:09:06.404529Z","steps":["trace[415705274] 'process raft request' (duration: 355.571737ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:06.404703Z","caller":"traceutil/trace.go:171","msg":"trace[1965906974] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:564; }","duration":"345.10896ms","start":"2026-03-02T02:09:06.059565Z","end":"2026-03-02T02:09:06.404674Z","steps":["trace[1965906974] 'read index received' (duration: 156.546382ms)","trace[1965906974] 'applied index is now lower than readState.Index' (duration: 188.561418ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:09:06.404737Z","caller":"traceutil/trace.go:171","msg":"trace[576091824] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"329.681351ms","start":"2026-03-02T02:09:06.075039Z","end":"2026-03-02T02:09:06.404721Z","steps":["trace[576091824] 'process raft request' (duration: 329.449795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:06.404904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.312275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T02:09:06.404921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.861081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:09:06.404945Z","caller":"traceutil/trace.go:171","msg":"trace[21336331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"345.402368ms","start":"2026-03-02T02:09:06.059533Z","end":"2026-03-02T02:09:06.404935Z","steps":["trace[21336331] 'agreement among raft nodes before linearized reading' (duration: 345.315316ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:09:06.404957Z","caller":"traceutil/trace.go:171","msg":"trace[1959239291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"113.930593ms","start":"2026-03-02T02:09:06.291014Z","end":"2026-03-02T02:09:06.404945Z","steps":["trace[1959239291] 'agreement among raft nodes before linearized reading' (duration: 113.876202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:09:06.40499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:06.059517Z","time spent":"345.465289ms","remote":"127.0.0.1:35924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T02:09:06.405031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:06.075022Z","time spent":"329.762685ms","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4749,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:09:06.404943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:09:06.048464Z","time spent":"356.184263ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:10:13.946281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.594909ms","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":"warn","ts":"2026-03-02T02:10:13.946359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.584894ms","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-03-02T02:10:13.946382Z","caller":"traceutil/trace.go:171","msg":"trace[1917841149] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1579; }","duration":"132.747702ms","start":"2026-03-02T02:10:13.813615Z","end":"2026-03-02T02:10:13.946362Z","steps":["trace[1917841149] 'range keys from in-memory index tree' (duration: 132.408834ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:10:13.946442Z","caller":"traceutil/trace.go:171","msg":"trace[321917534] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1579; }","duration":"227.711547ms","start":"2026-03-02T02:10:13.718709Z","end":"2026-03-02T02:10:13.94642Z","steps":["trace[321917534] 'range keys from in-memory index tree' (duration: 227.41725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:13.946462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.083042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-02T02:10:13.946552Z","caller":"traceutil/trace.go:171","msg":"trace[65736656] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:1579; }","duration":"284.217425ms","start":"2026-03-02T02:10:13.662312Z","end":"2026-03-02T02:10:13.94653Z","steps":["trace[65736656] 'range keys from in-memory index tree' (duration: 283.911087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:10:13.946563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.388403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fd0ec28f-1d4d-4fcc-aa33-e32dc30582b4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-02T02:10:13.946663Z","caller":"traceutil/trace.go:171","msg":"trace[230773611] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fd0ec28f-1d4d-4fcc-aa33-e32dc30582b4; range_end:; response_count:1; response_revision:1579; }","duration":"271.531617ms","start":"2026-03-02T02:10:13.67511Z","end":"2026-03-02T02:10:13.946642Z","steps":["trace[230773611] 'range keys from in-memory index tree' (duration: 271.225389ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:10:27.145134Z","caller":"traceutil/trace.go:171","msg":"trace[2091843736] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"103.071091ms","start":"2026-03-02T02:10:27.042038Z","end":"2026-03-02T02:10:27.145109Z","steps":["trace[2091843736] 'process raft request' (duration: 102.543678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:11:03.863498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.976435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:11:03.863752Z","caller":"traceutil/trace.go:171","msg":"trace[579508172] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"176.784898ms","start":"2026-03-02T02:11:03.686917Z","end":"2026-03-02T02:11:03.863702Z","steps":["trace[579508172] 'process raft request' (duration: 60.500775ms)","trace[579508172] 'compare' (duration: 115.824251ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:11:03.953573Z","caller":"traceutil/trace.go:171","msg":"trace[2035198962] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"130.826762ms","start":"2026-03-02T02:11:03.82271Z","end":"2026-03-02T02:11:03.953537Z","steps":["trace[2035198962] 'process raft request' (duration: 130.664418ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:12:18.349331Z","caller":"traceutil/trace.go:171","msg":"trace[1867180618] linearizableReadLoop","detail":"{readStateIndex:2532; appliedIndex:2531; }","duration":"114.717692ms","start":"2026-03-02T02:12:18.23459Z","end":"2026-03-02T02:12:18.349308Z","steps":["trace[1867180618] 'read index received' (duration: 114.502085ms)","trace[1867180618] 'applied index is now lower than readState.Index' (duration: 214.867µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:12:18.349499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.880416ms","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-03-02T02:12:18.349539Z","caller":"traceutil/trace.go:171","msg":"trace[597005363] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2436; }","duration":"114.943648ms","start":"2026-03-02T02:12:18.234585Z","end":"2026-03-02T02:12:18.349528Z","steps":["trace[597005363] 'agreement among raft nodes before linearized reading' (duration: 114.824264ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:12:18.349695Z","caller":"traceutil/trace.go:171","msg":"trace[185957587] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"136.356837ms","start":"2026-03-02T02:12:18.213158Z","end":"2026-03-02T02:12:18.349514Z","steps":["trace[185957587] 'process raft request' (duration: 136.010528ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:14:43.642889Z","caller":"traceutil/trace.go:171","msg":"trace[683144489] linearizableReadLoop","detail":"{readStateIndex:3460; appliedIndex:3459; }","duration":"169.114047ms","start":"2026-03-02T02:14:43.473754Z","end":"2026-03-02T02:14:43.642869Z","steps":["trace[683144489] 'read index received' (duration: 168.912782ms)","trace[683144489] 'applied index is now lower than readState.Index' (duration: 200.815µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:14:43.642967Z","caller":"traceutil/trace.go:171","msg":"trace[1642735164] transaction","detail":"{read_only:false; response_revision:3328; number_of_response:1; }","duration":"200.010764ms","start":"2026-03-02T02:14:43.442926Z","end":"2026-03-02T02:14:43.642937Z","steps":["trace[1642735164] 'process raft request' (duration: 199.794138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:14:43.643053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.283281ms","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-03-02T02:14:43.643479Z","caller":"traceutil/trace.go:171","msg":"trace[1355985427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3328; }","duration":"169.734433ms","start":"2026-03-02T02:14:43.473713Z","end":"2026-03-02T02:14:43.643447Z","steps":["trace[1355985427] 'agreement among raft nodes before linearized reading' (duration: 169.255301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:14:43.643104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.000627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-03-02T02:14:43.64364Z","caller":"traceutil/trace.go:171","msg":"trace[1536304196] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3328; }","duration":"168.556932ms","start":"2026-03-02T02:14:43.47506Z","end":"2026-03-02T02:14:43.643617Z","steps":["trace[1536304196] 'agreement among raft nodes before linearized reading' (duration: 168.010497ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:01.862204Z","caller":"traceutil/trace.go:171","msg":"trace[336401632] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4249; }","duration":"103.644645ms","start":"2026-03-02T02:16:01.758504Z","end":"2026-03-02T02:16:01.862148Z","steps":["trace[336401632] 'process raft request' (duration: 43.532554ms)","trace[336401632] 'compare' (duration: 59.874564ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:16:04.088269Z","caller":"traceutil/trace.go:171","msg":"trace[788348546] linearizableReadLoop","detail":"{readStateIndex:4493; appliedIndex:4491; }","duration":"112.960737ms","start":"2026-03-02T02:16:03.975274Z","end":"2026-03-02T02:16:04.088235Z","steps":["trace[788348546] 'read index received' (duration: 106.332428ms)","trace[788348546] 'applied index is now lower than readState.Index' (duration: 6.626749ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:16:04.088408Z","caller":"traceutil/trace.go:171","msg":"trace[600335152] transaction","detail":"{read_only:false; response_revision:4319; number_of_response:1; }","duration":"124.830287ms","start":"2026-03-02T02:16:03.963565Z","end":"2026-03-02T02:16:04.088396Z","steps":["trace[600335152] 'process raft request' (duration: 117.961212ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:04.088554Z","caller":"traceutil/trace.go:171","msg":"trace[979160761] transaction","detail":"{read_only:false; response_revision:4320; number_of_response:1; }","duration":"113.991404ms","start":"2026-03-02T02:16:03.974529Z","end":"2026-03-02T02:16:04.08852Z","steps":["trace[979160761] 'process raft request' (duration: 113.619695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:04.088878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.057383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-fv2n7-tls-nh7d4\" ","response":"range_response_count:1 size:2629"} {"level":"info","ts":"2026-03-02T02:16:04.08898Z","caller":"traceutil/trace.go:171","msg":"trace[1765614575] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-fv2n7-tls-nh7d4; range_end:; response_count:1; response_revision:4320; }","duration":"125.135195ms","start":"2026-03-02T02:16:03.963784Z","end":"2026-03-02T02:16:04.088919Z","steps":["trace[1765614575] 'agreement among raft nodes before linearized reading' (duration: 124.830517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:04.089371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.776973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-fv2n7-tls\" ","response":"range_response_count:1 size:4428"} {"level":"info","ts":"2026-03-02T02:16:04.089492Z","caller":"traceutil/trace.go:171","msg":"trace[2036808820] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-fv2n7-tls; range_end:; response_count:1; response_revision:4320; }","duration":"125.910697ms","start":"2026-03-02T02:16:03.963565Z","end":"2026-03-02T02:16:04.089476Z","steps":["trace[2036808820] 'agreement among raft nodes before linearized reading' (duration: 125.624728ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:04.09672Z","caller":"traceutil/trace.go:171","msg":"trace[1075197524] transaction","detail":"{read_only:false; response_revision:4322; number_of_response:1; }","duration":"107.295894ms","start":"2026-03-02T02:16:03.989407Z","end":"2026-03-02T02:16:04.096703Z","steps":["trace[1075197524] 'process raft request' (duration: 107.183501ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:04.096876Z","caller":"traceutil/trace.go:171","msg":"trace[1968260139] transaction","detail":"{read_only:false; response_revision:4321; number_of_response:1; }","duration":"107.438527ms","start":"2026-03-02T02:16:03.989427Z","end":"2026-03-02T02:16:04.096866Z","steps":["trace[1968260139] 'process raft request' (duration: 107.070758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:04.096729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.046377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-03-02T02:16:04.097148Z","caller":"traceutil/trace.go:171","msg":"trace[157170496] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4322; }","duration":"110.49873ms","start":"2026-03-02T02:16:03.986637Z","end":"2026-03-02T02:16:04.097136Z","steps":["trace[157170496] 'agreement among raft nodes before linearized reading' (duration: 110.009817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:26.881492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.595705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T02:16:26.881728Z","caller":"traceutil/trace.go:171","msg":"trace[52946285] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"222.122772ms","start":"2026-03-02T02:16:26.659542Z","end":"2026-03-02T02:16:26.881665Z","steps":["trace[52946285] 'process raft request' (duration: 26.28106ms)","trace[52946285] 'compare' (duration: 195.42096ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:16:26.881802Z","caller":"traceutil/trace.go:171","msg":"trace[1466100666] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5008; }","duration":"193.739216ms","start":"2026-03-02T02:16:26.688018Z","end":"2026-03-02T02:16:26.881757Z","steps":["trace[1466100666] 'read index received' (duration: 38.011µs)","trace[1466100666] 'applied index is now lower than readState.Index' (duration: 193.698204ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:16:26.882062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.031263ms","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-03-02T02:16:26.882105Z","caller":"traceutil/trace.go:171","msg":"trace[409850544] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4826; }","duration":"194.088574ms","start":"2026-03-02T02:16:26.688006Z","end":"2026-03-02T02:16:26.882094Z","steps":["trace[409850544] 'agreement among raft nodes before linearized reading' (duration: 193.894119ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:27.123074Z","caller":"traceutil/trace.go:171","msg":"trace[8338455] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"228.536175ms","start":"2026-03-02T02:16:26.894513Z","end":"2026-03-02T02:16:27.123049Z","steps":["trace[8338455] 'process raft request' (duration: 215.324238ms)","trace[8338455] 'compare' (duration: 12.816076ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:16:27.123226Z","caller":"traceutil/trace.go:171","msg":"trace[1151063547] linearizableReadLoop","detail":"{readStateIndex:5013; appliedIndex:5011; }","duration":"144.825207ms","start":"2026-03-02T02:16:26.97837Z","end":"2026-03-02T02:16:27.123195Z","steps":["trace[1151063547] 'read index received' (duration: 131.471806ms)","trace[1151063547] 'applied index is now lower than readState.Index' (duration: 13.352571ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:16:27.123332Z","caller":"traceutil/trace.go:171","msg":"trace[1438722882] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4829; }","duration":"225.477182ms","start":"2026-03-02T02:16:26.897843Z","end":"2026-03-02T02:16:27.12332Z","steps":["trace[1438722882] 'process raft request' (duration: 225.136223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:27.123496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.141841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T02:16:27.123549Z","caller":"traceutil/trace.go:171","msg":"trace[476144913] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4829; }","duration":"196.247194ms","start":"2026-03-02T02:16:26.927289Z","end":"2026-03-02T02:16:27.123537Z","steps":["trace[476144913] 'agreement among raft nodes before linearized reading' (duration: 196.127891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:27.123557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.978173ms","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-03-02T02:16:27.123591Z","caller":"traceutil/trace.go:171","msg":"trace[856687836] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4829; }","duration":"237.062855ms","start":"2026-03-02T02:16:26.886519Z","end":"2026-03-02T02:16:27.123582Z","steps":["trace[856687836] 'agreement among raft nodes before linearized reading' (duration: 236.904551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:27.123621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.39074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-50b94ff1-1075-4b74-b034-7bf913ba71ed\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-02T02:16:27.123675Z","caller":"traceutil/trace.go:171","msg":"trace[455500315] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-50b94ff1-1075-4b74-b034-7bf913ba71ed; range_end:; response_count:1; response_revision:4829; }","duration":"159.467571ms","start":"2026-03-02T02:16:26.964194Z","end":"2026-03-02T02:16:27.123662Z","steps":["trace[455500315] 'agreement among raft nodes before linearized reading' (duration: 159.291846ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:27.182894Z","caller":"traceutil/trace.go:171","msg":"trace[208991086] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"199.651215ms","start":"2026-03-02T02:16:26.983204Z","end":"2026-03-02T02:16:27.182855Z","steps":["trace[208991086] 'process raft request' (duration: 199.508381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:27.183031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.096821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:16:27.183159Z","caller":"traceutil/trace.go:171","msg":"trace[691330789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4830; }","duration":"123.222494ms","start":"2026-03-02T02:16:27.059865Z","end":"2026-03-02T02:16:27.183088Z","steps":["trace[691330789] 'agreement among raft nodes before linearized reading' (duration: 123.044349ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:27.407075Z","caller":"traceutil/trace.go:171","msg":"trace[78716024] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"278.743468ms","start":"2026-03-02T02:16:27.128168Z","end":"2026-03-02T02:16:27.406912Z","steps":["trace[78716024] 'process raft request' (duration: 181.444563ms)","trace[78716024] 'compare' (duration: 97.006397ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:16:27.407123Z","caller":"traceutil/trace.go:171","msg":"trace[1033804175] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"275.056169ms","start":"2026-03-02T02:16:27.132017Z","end":"2026-03-02T02:16:27.407073Z","steps":["trace[1033804175] 'process raft request' (duration: 274.771871ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:27.40773Z","caller":"traceutil/trace.go:171","msg":"trace[771152634] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"171.462524ms","start":"2026-03-02T02:16:27.236246Z","end":"2026-03-02T02:16:27.407709Z","steps":["trace[771152634] 'process raft request' (duration: 171.366582ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:27.407726Z","caller":"traceutil/trace.go:171","msg":"trace[1261396874] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"270.522436ms","start":"2026-03-02T02:16:27.137178Z","end":"2026-03-02T02:16:27.407701Z","steps":["trace[1261396874] 'process raft request' (duration: 269.687084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:16:27.40799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.712145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17306"} {"level":"info","ts":"2026-03-02T02:16:27.408041Z","caller":"traceutil/trace.go:171","msg":"trace[2047621414] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4834; }","duration":"282.794578ms","start":"2026-03-02T02:16:27.125236Z","end":"2026-03-02T02:16:27.408031Z","steps":["trace[2047621414] 'agreement among raft nodes before linearized reading' (duration: 282.607132ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:16:27.407774Z","caller":"traceutil/trace.go:171","msg":"trace[1154522128] linearizableReadLoop","detail":"{readStateIndex:5017; appliedIndex:5014; }","duration":"224.846705ms","start":"2026-03-02T02:16:27.182882Z","end":"2026-03-02T02:16:27.407729Z","steps":["trace[1154522128] 'read index received' (duration: 126.748079ms)","trace[1154522128] 'applied index is now lower than readState.Index' (duration: 98.096746ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:18:30.747872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2790} {"level":"info","ts":"2026-03-02T02:18:30.80584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2790,"took":"56.32818ms","hash":1739684640,"current-db-size-bytes":41709568,"current-db-size":"42 MB","current-db-size-in-use-bytes":31522816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-02T02:18:30.805892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1739684640,"revision":2790,"compact-revision":-1} {"level":"info","ts":"2026-03-02T02:22:33.910935Z","caller":"traceutil/trace.go:171","msg":"trace[540555542] linearizableReadLoop","detail":"{readStateIndex:7778; appliedIndex:7777; }","duration":"209.056371ms","start":"2026-03-02T02:22:33.701853Z","end":"2026-03-02T02:22:33.910909Z","steps":["trace[540555542] 'read index received' (duration: 208.866006ms)","trace[540555542] 'applied index is now lower than readState.Index' (duration: 189.215µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:22:33.911021Z","caller":"traceutil/trace.go:171","msg":"trace[605520027] transaction","detail":"{read_only:false; response_revision:7505; number_of_response:1; }","duration":"282.594892ms","start":"2026-03-02T02:22:33.628381Z","end":"2026-03-02T02:22:33.910976Z","steps":["trace[605520027] 'process raft request' (duration: 282.366196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:22:33.911222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.35579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-02T02:22:33.911312Z","caller":"traceutil/trace.go:171","msg":"trace[1483853866] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:7505; }","duration":"209.509123ms","start":"2026-03-02T02:22:33.701782Z","end":"2026-03-02T02:22:33.911291Z","steps":["trace[1483853866] 'agreement among raft nodes before linearized reading' (duration: 209.264487ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:22:34.397621Z","caller":"traceutil/trace.go:171","msg":"trace[1470594730] transaction","detail":"{read_only:false; response_revision:7507; number_of_response:1; }","duration":"139.23676ms","start":"2026-03-02T02:22:34.258351Z","end":"2026-03-02T02:22:34.397588Z","steps":["trace[1470594730] 'process raft request' (duration: 138.891111ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:03.056628Z","caller":"traceutil/trace.go:171","msg":"trace[977451505] transaction","detail":"{read_only:false; response_revision:7705; number_of_response:1; }","duration":"100.231002ms","start":"2026-03-02T02:23:02.956357Z","end":"2026-03-02T02:23:03.056588Z","steps":["trace[977451505] 'process raft request' (duration: 99.993686ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:14.108609Z","caller":"traceutil/trace.go:171","msg":"trace[2096392112] transaction","detail":"{read_only:false; response_revision:7798; number_of_response:1; }","duration":"166.957526ms","start":"2026-03-02T02:23:13.941623Z","end":"2026-03-02T02:23:14.10858Z","steps":["trace[2096392112] 'process raft request' (duration: 166.72744ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:14.217391Z","caller":"traceutil/trace.go:171","msg":"trace[600835409] transaction","detail":"{read_only:false; response_revision:7799; number_of_response:1; }","duration":"164.758196ms","start":"2026-03-02T02:23:14.052594Z","end":"2026-03-02T02:23:14.217353Z","steps":["trace[600835409] 'process raft request' (duration: 144.164158ms)","trace[600835409] 'compare' (duration: 20.259729ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:23:14.217508Z","caller":"traceutil/trace.go:171","msg":"trace[2138979980] linearizableReadLoop","detail":"{readStateIndex:8087; appliedIndex:8085; }","duration":"108.951618ms","start":"2026-03-02T02:23:14.108532Z","end":"2026-03-02T02:23:14.217484Z","steps":["trace[2138979980] 'read index received' (duration: 88.159235ms)","trace[2138979980] 'applied index is now lower than readState.Index' (duration: 20.791473ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T02:23:14.217642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.661701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T02:23:14.217615Z","caller":"traceutil/trace.go:171","msg":"trace[274145515] transaction","detail":"{read_only:false; response_revision:7800; number_of_response:1; }","duration":"135.515596ms","start":"2026-03-02T02:23:14.082037Z","end":"2026-03-02T02:23:14.217553Z","steps":["trace[274145515] 'process raft request' (duration: 135.197898ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:14.217685Z","caller":"traceutil/trace.go:171","msg":"trace[32610306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7800; }","duration":"158.782985ms","start":"2026-03-02T02:23:14.058891Z","end":"2026-03-02T02:23:14.217674Z","steps":["trace[32610306] 'agreement among raft nodes before linearized reading' (duration: 158.698102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:23:14.217722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.391051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-4mktx\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-03-02T02:23:14.217757Z","caller":"traceutil/trace.go:171","msg":"trace[1223669225] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-4mktx; range_end:; response_count:1; response_revision:7800; }","duration":"105.482344ms","start":"2026-03-02T02:23:14.112266Z","end":"2026-03-02T02:23:14.217748Z","steps":["trace[1223669225] 'agreement among raft nodes before linearized reading' (duration: 105.35192ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:15.018321Z","caller":"traceutil/trace.go:171","msg":"trace[615876516] transaction","detail":"{read_only:false; response_revision:7806; number_of_response:1; }","duration":"267.325451ms","start":"2026-03-02T02:23:14.750971Z","end":"2026-03-02T02:23:15.018296Z","steps":["trace[615876516] 'process raft request' (duration: 267.162807ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:15.067181Z","caller":"traceutil/trace.go:171","msg":"trace[2100319773] transaction","detail":"{read_only:false; response_revision:7807; number_of_response:1; }","duration":"315.925585ms","start":"2026-03-02T02:23:14.751227Z","end":"2026-03-02T02:23:15.067153Z","steps":["trace[2100319773] 'process raft request' (duration: 315.73288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:23:15.067368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:23:14.751207Z","time spent":"316.054598ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T02:23:15.067416Z","caller":"traceutil/trace.go:171","msg":"trace[1477678927] transaction","detail":"{read_only:false; response_revision:7808; number_of_response:1; }","duration":"314.781403ms","start":"2026-03-02T02:23:14.752606Z","end":"2026-03-02T02:23:15.067387Z","steps":["trace[1477678927] 'process raft request' (duration: 314.496535ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:15.067466Z","caller":"traceutil/trace.go:171","msg":"trace[1895558570] linearizableReadLoop","detail":"{readStateIndex:8096; appliedIndex:8092; }","duration":"171.480737ms","start":"2026-03-02T02:23:14.895959Z","end":"2026-03-02T02:23:15.06744Z","steps":["trace[1895558570] 'read index received' (duration: 122.278818ms)","trace[1895558570] 'applied index is now lower than readState.Index' (duration: 49.200369ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T02:23:15.067542Z","caller":"traceutil/trace.go:171","msg":"trace[1573484284] transaction","detail":"{read_only:false; response_revision:7810; number_of_response:1; }","duration":"139.566095ms","start":"2026-03-02T02:23:14.927959Z","end":"2026-03-02T02:23:15.067525Z","steps":["trace[1573484284] 'process raft request' (duration: 139.423371ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:15.067542Z","caller":"traceutil/trace.go:171","msg":"trace[879171304] transaction","detail":"{read_only:false; response_revision:7809; number_of_response:1; }","duration":"314.065355ms","start":"2026-03-02T02:23:14.753452Z","end":"2026-03-02T02:23:15.067517Z","steps":["trace[879171304] 'process raft request' (duration: 313.847039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T02:23:15.067575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:23:14.752583Z","time spent":"314.901077ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:23:15.067692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T02:23:14.753434Z","time spent":"314.164557ms","remote":"127.0.0.1:49332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T02:23:15.067739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.777615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-02T02:23:15.067804Z","caller":"traceutil/trace.go:171","msg":"trace[1873198534] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:7810; }","duration":"171.883838ms","start":"2026-03-02T02:23:14.895906Z","end":"2026-03-02T02:23:15.06779Z","steps":["trace[1873198534] 'agreement among raft nodes before linearized reading' (duration: 171.695953ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:15.643138Z","caller":"traceutil/trace.go:171","msg":"trace[836818612] transaction","detail":"{read_only:false; response_revision:7814; number_of_response:1; }","duration":"157.546182ms","start":"2026-03-02T02:23:15.48556Z","end":"2026-03-02T02:23:15.643106Z","steps":["trace[836818612] 'process raft request' (duration: 157.366057ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T02:23:30.754126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5801} {"level":"info","ts":"2026-03-02T02:23:30.839582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5801,"took":"82.692016ms","hash":2480007188,"current-db-size-bytes":43556864,"current-db-size":"44 MB","current-db-size-in-use-bytes":23953408,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-02T02:23:30.839661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2480007188,"revision":5801,"compact-revision":2790} {"level":"info","ts":"2026-03-02T02:27:03.271839Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-02T02:27:03.278391Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-02T02:27:03.278489Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-02T02:28:30.762993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8000} {"level":"info","ts":"2026-03-02T02:28:30.85618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8000,"took":"89.143023ms","hash":3301138042,"current-db-size-bytes":43556864,"current-db-size":"44 MB","current-db-size-in-use-bytes":23572480,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-02T02:28:30.856266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3301138042,"revision":8000,"compact-revision":5801} {"level":"info","ts":"2026-03-02T02:33:30.768886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10387} {"level":"info","ts":"2026-03-02T02:33:30.851589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10387,"took":"77.577208ms","hash":3195895210,"current-db-size-bytes":43556864,"current-db-size":"44 MB","current-db-size-in-use-bytes":24756224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-02T02:33:30.851737Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3195895210,"revision":10387,"compact-revision":8000}