{"level":"warn","ts":"2026-04-03T01:32:44.595089Z","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-03T01:32:44.595228Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-03T01:32:44.595337Z","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-03T01:32:44.595355Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-03T01:32:44.595378Z","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-03T01:32:44.596258Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-04-03T01:32:44.5964Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-03T01:32:44.600491Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.79419ms"} {"level":"info","ts":"2026-04-03T01:32:44.605769Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-04-03T01:32:44.60585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T01:32:44.605872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-04-03T01:32:44.605884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T01:32:44.6059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-04-03T01:32:44.605946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-04-03T01:32:44.615273Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T01:32:44.616905Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T01:32:44.618617Z","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-03T01:32:44.621714Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T01:32:44.622861Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T01:32:44.623015Z","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-03T01:32:44.623128Z","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-03T01:32:44.623143Z","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-03T01:32:44.628354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-04-03T01:32:44.628512Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-03T01:32:44.629367Z","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-03T01:32:44.629563Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-03T01:32:44.629594Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-03T01:32:44.629775Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T01:32:44.629854Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T01:32:44.906141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T01:32:44.906208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T01:32:44.906245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-04-03T01:32:44.906273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-04-03T01:32:44.906296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-03T01:32:44.906321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-04-03T01:32:44.906343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-03T01:32:44.907268Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T01:32:44.908007Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T01:32:44.908004Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T01:32:44.908039Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T01:32:44.908379Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T01:32:44.908415Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T01:32:44.909887Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T01:32:44.911414Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-04-03T01:32:44.9116Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T01:32:44.911659Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T01:32:44.913953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T01:32:46.612933Z","caller":"traceutil/trace.go:171","msg":"trace[702205074] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"249.632799ms","start":"2026-04-03T01:32:46.363273Z","end":"2026-04-03T01:32:46.612906Z","steps":["trace[702205074] 'read index received' (duration: 70.957425ms)","trace[702205074] 'applied index is now lower than readState.Index' (duration: 178.674134ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:32:46.61298Z","caller":"traceutil/trace.go:171","msg":"trace[109649598] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"249.799281ms","start":"2026-04-03T01:32:46.363119Z","end":"2026-04-03T01:32:46.612919Z","steps":["trace[109649598] 'process raft request' (duration: 214.427295ms)","trace[109649598] 'compare' (duration: 35.236196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:32:46.613321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.959402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:32:46.613398Z","caller":"traceutil/trace.go:171","msg":"trace[388398707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:3; }","duration":"250.156365ms","start":"2026-04-03T01:32:46.363229Z","end":"2026-04-03T01:32:46.613386Z","steps":["trace[388398707] 'agreement among raft nodes before linearized reading' (duration: 249.832133ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:46.618164Z","caller":"traceutil/trace.go:171","msg":"trace[1953470884] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"176.333961ms","start":"2026-04-03T01:32:46.441802Z","end":"2026-04-03T01:32:46.618136Z","steps":["trace[1953470884] 'process raft request' (duration: 175.394895ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:46.618179Z","caller":"traceutil/trace.go:171","msg":"trace[785562882] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"176.244224ms","start":"2026-04-03T01:32:46.441653Z","end":"2026-04-03T01:32:46.617897Z","steps":["trace[785562882] 'process raft request' (duration: 175.515993ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:46.618373Z","caller":"traceutil/trace.go:171","msg":"trace[669399981] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"176.443728ms","start":"2026-04-03T01:32:46.441914Z","end":"2026-04-03T01:32:46.618357Z","steps":["trace[669399981] 'process raft request' (duration: 175.295838ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:46.618829Z","caller":"traceutil/trace.go:171","msg":"trace[101668639] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"176.197511ms","start":"2026-04-03T01:32:46.442228Z","end":"2026-04-03T01:32:46.618425Z","steps":["trace[101668639] 'process raft request' (duration: 175.012438ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:46.618961Z","caller":"traceutil/trace.go:171","msg":"trace[255167042] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"180.162857ms","start":"2026-04-03T01:32:46.43877Z","end":"2026-04-03T01:32:46.618933Z","steps":["trace[255167042] 'process raft request' (duration: 178.244523ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:46.619479Z","caller":"traceutil/trace.go:171","msg":"trace[451037024] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"170.975136ms","start":"2026-04-03T01:32:46.448125Z","end":"2026-04-03T01:32:46.6191Z","steps":["trace[451037024] 'process raft request' (duration: 169.454001ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:46.619723Z","caller":"traceutil/trace.go:171","msg":"trace[785334642] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"178.130154ms","start":"2026-04-03T01:32:46.441545Z","end":"2026-04-03T01:32:46.619675Z","steps":["trace[785334642] 'process raft request' (duration: 175.588438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:32:46.624487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.174143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:32:46.624543Z","caller":"traceutil/trace.go:171","msg":"trace[422486310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"261.27055ms","start":"2026-04-03T01:32:46.363252Z","end":"2026-04-03T01:32:46.624522Z","steps":["trace[422486310] 'agreement among raft nodes before linearized reading' (duration: 261.12228ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:46.624897Z","caller":"traceutil/trace.go:171","msg":"trace[1751095257] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"171.628312ms","start":"2026-04-03T01:32:46.453232Z","end":"2026-04-03T01:32:46.624861Z","steps":["trace[1751095257] 'process raft request' (duration: 164.629164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:32:46.625082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.935718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:32:46.625124Z","caller":"traceutil/trace.go:171","msg":"trace[793544752] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"122.043726ms","start":"2026-04-03T01:32:46.503067Z","end":"2026-04-03T01:32:46.62511Z","steps":["trace[793544752] 'agreement among raft nodes before linearized reading' (duration: 121.984422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:32:46.6253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.328092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:32:46.625332Z","caller":"traceutil/trace.go:171","msg":"trace[1707438423] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"135.382006ms","start":"2026-04-03T01:32:46.489938Z","end":"2026-04-03T01:32:46.62532Z","steps":["trace[1707438423] 'agreement among raft nodes before linearized reading' (duration: 135.342703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:32:46.625389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.395539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-04-03T01:32:46.625473Z","caller":"traceutil/trace.go:171","msg":"trace[1352903576] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"259.542139ms","start":"2026-04-03T01:32:46.365917Z","end":"2026-04-03T01:32:46.625459Z","steps":["trace[1352903576] 'agreement among raft nodes before linearized reading' (duration: 259.382288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:32:46.625648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.890407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:32:46.625698Z","caller":"traceutil/trace.go:171","msg":"trace[1066225739] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"184.93959ms","start":"2026-04-03T01:32:46.440731Z","end":"2026-04-03T01:32:46.62567Z","steps":["trace[1066225739] 'agreement among raft nodes before linearized reading' (duration: 184.891536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:32:46.625924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.840058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:32:46.625986Z","caller":"traceutil/trace.go:171","msg":"trace[507947821] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"203.907362ms","start":"2026-04-03T01:32:46.422043Z","end":"2026-04-03T01:32:46.62595Z","steps":["trace[507947821] 'agreement among raft nodes before linearized reading' (duration: 203.842358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:32:46.625981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.592885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:32:46.626149Z","caller":"traceutil/trace.go:171","msg":"trace[36051301] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:0; response_revision:11; }","duration":"189.863193ms","start":"2026-04-03T01:32:46.436206Z","end":"2026-04-03T01:32:46.626069Z","steps":["trace[36051301] 'agreement among raft nodes before linearized reading' (duration: 189.66897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:32:46.626155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.653369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-03T01:32:46.626267Z","caller":"traceutil/trace.go:171","msg":"trace[447231898] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"187.853413ms","start":"2026-04-03T01:32:46.438368Z","end":"2026-04-03T01:32:46.626221Z","steps":["trace[447231898] 'agreement among raft nodes before linearized reading' (duration: 187.639928ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:50.790513Z","caller":"traceutil/trace.go:171","msg":"trace[1814319380] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"103.020389ms","start":"2026-04-03T01:32:50.687454Z","end":"2026-04-03T01:32:50.790474Z","steps":["trace[1814319380] 'process raft request' (duration: 102.788603ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:32:50.790571Z","caller":"traceutil/trace.go:171","msg":"trace[2031901179] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"101.612907ms","start":"2026-04-03T01:32:50.688931Z","end":"2026-04-03T01:32:50.790544Z","steps":["trace[2031901179] 'process raft request' (duration: 101.423684ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:33:20.467518Z","caller":"traceutil/trace.go:171","msg":"trace[1543694391] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"284.890402ms","start":"2026-04-03T01:33:20.182565Z","end":"2026-04-03T01:33:20.467456Z","steps":["trace[1543694391] 'process raft request' (duration: 278.565577ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:33:20.652098Z","caller":"traceutil/trace.go:171","msg":"trace[209695901] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"127.307344ms","start":"2026-04-03T01:33:20.524752Z","end":"2026-04-03T01:33:20.65206Z","steps":["trace[209695901] 'process raft request' (duration: 101.521173ms)","trace[209695901] 'compare' (duration: 25.512089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:33:21.272935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.799057ms","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-04-03T01:33:21.273095Z","caller":"traceutil/trace.go:171","msg":"trace[544069814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"245.004894ms","start":"2026-04-03T01:33:21.028064Z","end":"2026-04-03T01:33:21.273069Z","steps":["trace[544069814] 'range keys from in-memory index tree' (duration: 244.59807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:33:21.273185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.310843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p2vcb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-03T01:33:21.27331Z","caller":"traceutil/trace.go:171","msg":"trace[1037094347] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p2vcb; range_end:; response_count:1; response_revision:607; }","duration":"467.518421ms","start":"2026-04-03T01:33:20.805751Z","end":"2026-04-03T01:33:21.27327Z","steps":["trace[1037094347] 'range keys from in-memory index tree' (duration: 467.045702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:33:21.273375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:33:20.805727Z","time spent":"467.635731ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-p2vcb\" "} {"level":"warn","ts":"2026-04-03T01:33:21.273591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.708055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-03T01:33:21.273651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.724833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-03T01:33:21.27375Z","caller":"traceutil/trace.go:171","msg":"trace[1710206225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"239.892286ms","start":"2026-04-03T01:33:21.03384Z","end":"2026-04-03T01:33:21.273732Z","steps":["trace[1710206225] 'range keys from in-memory index tree' (duration: 238.935297ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:33:21.274013Z","caller":"traceutil/trace.go:171","msg":"trace[1353757891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"246.908652ms","start":"2026-04-03T01:33:21.026794Z","end":"2026-04-03T01:33:21.273703Z","steps":["trace[1353757891] 'range keys from in-memory index tree' (duration: 246.112296ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:33:21.27486Z","caller":"traceutil/trace.go:171","msg":"trace[1962317447] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"213.874611ms","start":"2026-04-03T01:33:21.060929Z","end":"2026-04-03T01:33:21.274804Z","steps":["trace[1962317447] 'process raft request' (duration: 211.785697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:33:38.296582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.642034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T01:33:38.296699Z","caller":"traceutil/trace.go:171","msg":"trace[2059881369] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:784; }","duration":"155.871483ms","start":"2026-04-03T01:33:38.140804Z","end":"2026-04-03T01:33:38.296676Z","steps":["trace[2059881369] 'range keys from in-memory index tree' (duration: 155.332167ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:34:09.162983Z","caller":"traceutil/trace.go:171","msg":"trace[116609655] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"240.725546ms","start":"2026-04-03T01:34:08.922222Z","end":"2026-04-03T01:34:09.162948Z","steps":["trace[116609655] 'process raft request' (duration: 240.32299ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:34:10.911048Z","caller":"traceutil/trace.go:171","msg":"trace[1791257123] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"140.846121ms","start":"2026-04-03T01:34:10.770174Z","end":"2026-04-03T01:34:10.91102Z","steps":["trace[1791257123] 'process raft request' (duration: 140.652883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:34:12.329521Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053292834s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T01:34:12.330246Z","caller":"traceutil/trace.go:171","msg":"trace[684986902] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"1.054158449s","start":"2026-04-03T01:34:11.276055Z","end":"2026-04-03T01:34:12.330214Z","steps":["trace[684986902] 'read index received' (duration: 170.809µs)","trace[684986902] 'applied index is now lower than readState.Index' (duration: 1.05398644s)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:34:12.330358Z","caller":"traceutil/trace.go:171","msg":"trace[1084716404] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"1.054364439s","start":"2026-04-03T01:34:11.275958Z","end":"2026-04-03T01:34:12.330322Z","steps":["trace[1084716404] 'process raft request' (duration: 1.05403565s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:34:12.330537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054434228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-04-03T01:34:12.330647Z","caller":"traceutil/trace.go:171","msg":"trace[1485803277] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1196; }","duration":"1.054633507s","start":"2026-04-03T01:34:11.275994Z","end":"2026-04-03T01:34:12.330627Z","steps":["trace[1485803277] 'agreement among raft nodes before linearized reading' (duration: 1.054358748s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:34:12.330725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:34:11.275965Z","time spent":"1.054735706s","remote":"127.0.0.1:59030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":247,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"info","ts":"2026-04-03T01:34:12.330794Z","caller":"traceutil/trace.go:171","msg":"trace[1405815149] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"1.054704726s","start":"2026-04-03T01:34:11.276075Z","end":"2026-04-03T01:34:12.33078Z","steps":["trace[1405815149] 'process raft request' (duration: 1.054602547s)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:34:12.331372Z","caller":"traceutil/trace.go:171","msg":"trace[1729819827] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"774.909063ms","start":"2026-04-03T01:34:11.556448Z","end":"2026-04-03T01:34:12.331357Z","steps":["trace[1729819827] 'process raft request' (duration: 774.294116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:34:12.331498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055279503s","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-03T01:34:12.33164Z","caller":"traceutil/trace.go:171","msg":"trace[156167111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1198; }","duration":"1.055500602s","start":"2026-04-03T01:34:11.27611Z","end":"2026-04-03T01:34:12.331611Z","steps":["trace[156167111] 'agreement among raft nodes before linearized reading' (duration: 1.055130334s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:34:12.331751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:34:11.276101Z","time spent":"1.055631402s","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T01:34:12.331835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.869535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T01:34:12.331967Z","caller":"traceutil/trace.go:171","msg":"trace[509801851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"784.150273ms","start":"2026-04-03T01:34:11.547799Z","end":"2026-04-03T01:34:12.331949Z","steps":["trace[509801851] 'agreement among raft nodes before linearized reading' (duration: 783.698625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:34:12.332053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:34:11.547775Z","time spent":"784.268403ms","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T01:34:12.332797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.45815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-03T01:34:12.332817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:34:11.275918Z","time spent":"1.054631626s","remote":"127.0.0.1:59070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3148,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T01:34:12.33285Z","caller":"traceutil/trace.go:171","msg":"trace[2122680370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"789.573889ms","start":"2026-04-03T01:34:11.543262Z","end":"2026-04-03T01:34:12.332836Z","steps":["trace[2122680370] 'agreement among raft nodes before linearized reading' (duration: 788.565924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:34:12.332891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:34:11.543241Z","time spent":"789.640649ms","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-03T01:34:12.332988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:34:11.556421Z","time spent":"775.013182ms","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:34:12.333145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:34:11.276054Z","time spent":"1.054789116s","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:34:12.335676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.231305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-03T01:34:12.335752Z","caller":"traceutil/trace.go:171","msg":"trace[751680501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"792.367344ms","start":"2026-04-03T01:34:11.543366Z","end":"2026-04-03T01:34:12.335734Z","steps":["trace[751680501] 'agreement among raft nodes before linearized reading' (duration: 792.133985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:34:12.336853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:34:11.543327Z","time spent":"793.503657ms","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T01:34:29.237976Z","caller":"traceutil/trace.go:171","msg":"trace[1808838763] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"227.163067ms","start":"2026-04-03T01:34:29.010786Z","end":"2026-04-03T01:34:29.237949Z","steps":["trace[1808838763] 'process raft request' (duration: 227.020544ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:34:32.566435Z","caller":"traceutil/trace.go:171","msg":"trace[1253903820] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"127.841107ms","start":"2026-04-03T01:34:32.438545Z","end":"2026-04-03T01:34:32.566386Z","steps":["trace[1253903820] 'process raft request' (duration: 79.969464ms)","trace[1253903820] 'compare' (duration: 47.563588ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:34:32.68096Z","caller":"traceutil/trace.go:171","msg":"trace[553633011] linearizableReadLoop","detail":"{readStateIndex:1498; appliedIndex:1496; }","duration":"194.705841ms","start":"2026-04-03T01:34:32.486228Z","end":"2026-04-03T01:34:32.680934Z","steps":["trace[553633011] 'read index received' (duration: 32.296084ms)","trace[553633011] 'applied index is now lower than readState.Index' (duration: 162.408187ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:34:32.681223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.648817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-03T01:34:32.681223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.988116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-03T01:34:32.68128Z","caller":"traceutil/trace.go:171","msg":"trace[1917462601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1450; }","duration":"190.748288ms","start":"2026-04-03T01:34:32.490518Z","end":"2026-04-03T01:34:32.681267Z","steps":["trace[1917462601] 'agreement among raft nodes before linearized reading' (duration: 190.590506ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:34:32.681291Z","caller":"traceutil/trace.go:171","msg":"trace[221744166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1450; }","duration":"195.111037ms","start":"2026-04-03T01:34:32.486164Z","end":"2026-04-03T01:34:32.681275Z","steps":["trace[221744166] 'agreement among raft nodes before linearized reading' (duration: 194.920854ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:34:32.681044Z","caller":"traceutil/trace.go:171","msg":"trace[1912688037] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"240.028023ms","start":"2026-04-03T01:34:32.440976Z","end":"2026-04-03T01:34:32.681004Z","steps":["trace[1912688037] 'process raft request' (duration: 239.775389ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:34:56.850555Z","caller":"traceutil/trace.go:171","msg":"trace[137788976] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"220.191999ms","start":"2026-04-03T01:34:56.630332Z","end":"2026-04-03T01:34:56.850524Z","steps":["trace[137788976] 'process raft request' (duration: 161.283866ms)","trace[137788976] 'compare' (duration: 58.22366ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:35:02.829589Z","caller":"traceutil/trace.go:171","msg":"trace[351521055] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"104.34155ms","start":"2026-04-03T01:35:02.725188Z","end":"2026-04-03T01:35:02.829529Z","steps":["trace[351521055] 'process raft request' (duration: 83.510187ms)","trace[351521055] 'compare' (duration: 20.409666ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:35:02.829587Z","caller":"traceutil/trace.go:171","msg":"trace[298912852] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"104.384472ms","start":"2026-04-03T01:35:02.725184Z","end":"2026-04-03T01:35:02.829569Z","steps":["trace[298912852] 'process raft request' (duration: 104.218506ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:35:02.830134Z","caller":"traceutil/trace.go:171","msg":"trace[1422590684] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"104.812268ms","start":"2026-04-03T01:35:02.725255Z","end":"2026-04-03T01:35:02.830068Z","steps":["trace[1422590684] 'process raft request' (duration: 104.255177ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:35:29.320625Z","caller":"traceutil/trace.go:171","msg":"trace[243301127] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"166.927983ms","start":"2026-04-03T01:35:29.153652Z","end":"2026-04-03T01:35:29.32058Z","steps":["trace[243301127] 'process raft request' (duration: 135.082421ms)","trace[243301127] 'compare' (duration: 31.685924ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:35:29.352336Z","caller":"traceutil/trace.go:171","msg":"trace[1049375470] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"198.248027ms","start":"2026-04-03T01:35:29.154051Z","end":"2026-04-03T01:35:29.352299Z","steps":["trace[1049375470] 'process raft request' (duration: 198.031967ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:35:29.352579Z","caller":"traceutil/trace.go:171","msg":"trace[1144462135] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"198.027025ms","start":"2026-04-03T01:35:29.154502Z","end":"2026-04-03T01:35:29.352529Z","steps":["trace[1144462135] 'process raft request' (duration: 197.737691ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:35:29.353125Z","caller":"traceutil/trace.go:171","msg":"trace[247744551] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2188; }","duration":"173.858708ms","start":"2026-04-03T01:35:29.179243Z","end":"2026-04-03T01:35:29.353101Z","steps":["trace[247744551] 'read index received' (duration: 109.511873ms)","trace[247744551] 'applied index is now lower than readState.Index' (duration: 64.345225ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:35:29.353274Z","caller":"traceutil/trace.go:171","msg":"trace[994220581] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"198.241897ms","start":"2026-04-03T01:35:29.154964Z","end":"2026-04-03T01:35:29.353205Z","steps":["trace[994220581] 'process raft request' (duration: 197.486249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:35:29.35338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.104202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T01:35:29.353457Z","caller":"traceutil/trace.go:171","msg":"trace[1378518080] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2118; }","duration":"174.27221ms","start":"2026-04-03T01:35:29.179163Z","end":"2026-04-03T01:35:29.353435Z","steps":["trace[1378518080] 'agreement among raft nodes before linearized reading' (duration: 174.109202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:35:29.354606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.705452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:35:29.354729Z","caller":"traceutil/trace.go:171","msg":"trace[1088376764] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2118; }","duration":"133.893971ms","start":"2026-04-03T01:35:29.22081Z","end":"2026-04-03T01:35:29.354704Z","steps":["trace[1088376764] 'agreement among raft nodes before linearized reading' (duration: 132.524793ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:24.757286Z","caller":"traceutil/trace.go:171","msg":"trace[527926564] linearizableReadLoop","detail":"{readStateIndex:2698; appliedIndex:2697; }","duration":"106.450874ms","start":"2026-04-03T01:36:24.650808Z","end":"2026-04-03T01:36:24.757259Z","steps":["trace[527926564] 'read index received' (duration: 106.22182ms)","trace[527926564] 'applied index is now lower than readState.Index' (duration: 226.924µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:36:24.757445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.609795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:36:24.757484Z","caller":"traceutil/trace.go:171","msg":"trace[2046697131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2598; }","duration":"106.722112ms","start":"2026-04-03T01:36:24.650751Z","end":"2026-04-03T01:36:24.757473Z","steps":["trace[2046697131] 'agreement among raft nodes before linearized reading' (duration: 106.631136ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:24.757702Z","caller":"traceutil/trace.go:171","msg":"trace[1168615897] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"184.213385ms","start":"2026-04-03T01:36:24.57344Z","end":"2026-04-03T01:36:24.757653Z","steps":["trace[1168615897] 'process raft request' (duration: 183.588816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:52.119184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.406162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:36:52.119315Z","caller":"traceutil/trace.go:171","msg":"trace[2134040602] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2787; }","duration":"107.607235ms","start":"2026-04-03T01:36:52.011667Z","end":"2026-04-03T01:36:52.119274Z","steps":["trace[2134040602] 'count revisions from in-memory index tree' (duration: 107.296074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:52.119515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.676906ms","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-03T01:36:52.119759Z","caller":"traceutil/trace.go:171","msg":"trace[1930577754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2787; }","duration":"115.972036ms","start":"2026-04-03T01:36:52.003755Z","end":"2026-04-03T01:36:52.119727Z","steps":["trace[1930577754] 'range keys from in-memory index tree' (duration: 115.395128ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:36:52.278063Z","caller":"traceutil/trace.go:171","msg":"trace[279657812] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"152.5425ms","start":"2026-04-03T01:36:52.125474Z","end":"2026-04-03T01:36:52.278017Z","steps":["trace[279657812] 'process raft request' (duration: 152.296963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:36:52.477401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.458436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T01:36:52.477849Z","caller":"traceutil/trace.go:171","msg":"trace[2075288168] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"190.526646ms","start":"2026-04-03T01:36:52.287257Z","end":"2026-04-03T01:36:52.477784Z","steps":["trace[2075288168] 'process raft request' (duration: 72.143638ms)","trace[2075288168] 'compare' (duration: 117.192228ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:37:56.770952Z","caller":"traceutil/trace.go:171","msg":"trace[966170372] transaction","detail":"{read_only:false; response_revision:3311; number_of_response:1; }","duration":"105.088175ms","start":"2026-04-03T01:37:56.665825Z","end":"2026-04-03T01:37:56.770913Z","steps":["trace[966170372] 'process raft request' (duration: 104.828504ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:37:56.771746Z","caller":"traceutil/trace.go:171","msg":"trace[912293706] transaction","detail":"{read_only:false; response_revision:3312; number_of_response:1; }","duration":"102.866716ms","start":"2026-04-03T01:37:56.668832Z","end":"2026-04-03T01:37:56.771698Z","steps":["trace[912293706] 'process raft request' (duration: 102.712301ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:54.213142Z","caller":"traceutil/trace.go:171","msg":"trace[719596744] transaction","detail":"{read_only:false; response_revision:3768; number_of_response:1; }","duration":"237.821028ms","start":"2026-04-03T01:38:53.975291Z","end":"2026-04-03T01:38:54.213112Z","steps":["trace[719596744] 'process raft request' (duration: 237.620278ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:54.214405Z","caller":"traceutil/trace.go:171","msg":"trace[1613232691] linearizableReadLoop","detail":"{readStateIndex:3945; appliedIndex:3944; }","duration":"119.980571ms","start":"2026-04-03T01:38:54.094395Z","end":"2026-04-03T01:38:54.214376Z","steps":["trace[1613232691] 'read index received' (duration: 118.786712ms)","trace[1613232691] 'applied index is now lower than readState.Index' (duration: 1.192499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:38:54.214527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.096111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:38:54.214576Z","caller":"traceutil/trace.go:171","msg":"trace[1804590786] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:3769; }","duration":"120.216141ms","start":"2026-04-03T01:38:54.094344Z","end":"2026-04-03T01:38:54.21456Z","steps":["trace[1804590786] 'agreement among raft nodes before linearized reading' (duration: 120.107571ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:38:54.214792Z","caller":"traceutil/trace.go:171","msg":"trace[1705789762] transaction","detail":"{read_only:false; response_revision:3769; number_of_response:1; }","duration":"237.723509ms","start":"2026-04-03T01:38:53.977029Z","end":"2026-04-03T01:38:54.214752Z","steps":["trace[1705789762] 'process raft request' (duration: 237.25494ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:20.158711Z","caller":"traceutil/trace.go:171","msg":"trace[12847582] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"171.60421ms","start":"2026-04-03T01:39:19.987056Z","end":"2026-04-03T01:39:20.15866Z","steps":["trace[12847582] 'process raft request' (duration: 171.401808ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:20.421251Z","caller":"traceutil/trace.go:171","msg":"trace[1106382733] linearizableReadLoop","detail":"{readStateIndex:4721; appliedIndex:4719; }","duration":"396.321878ms","start":"2026-04-03T01:39:20.024899Z","end":"2026-04-03T01:39:20.421221Z","steps":["trace[1106382733] 'read index received' (duration: 133.515094ms)","trace[1106382733] 'applied index is now lower than readState.Index' (duration: 262.794544ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:20.421275Z","caller":"traceutil/trace.go:171","msg":"trace[942910556] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"422.842606ms","start":"2026-04-03T01:39:19.99839Z","end":"2026-04-03T01:39:20.421233Z","steps":["trace[942910556] 'process raft request' (duration: 422.629984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:20.421475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:19.998378Z","time spent":"422.990278ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T01:39:20.421678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.738322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c2e5a59-dcbc-46f2-9749-da903cffa9ed\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-03T01:39:20.42178Z","caller":"traceutil/trace.go:171","msg":"trace[1993672648] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5c2e5a59-dcbc-46f2-9749-da903cffa9ed; range_end:; response_count:1; response_revision:4527; }","duration":"396.929595ms","start":"2026-04-03T01:39:20.024824Z","end":"2026-04-03T01:39:20.421753Z","steps":["trace[1993672648] 'agreement among raft nodes before linearized reading' (duration: 396.527551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:20.421846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T01:39:20.024794Z","time spent":"397.040596ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c2e5a59-dcbc-46f2-9749-da903cffa9ed\" "} {"level":"info","ts":"2026-04-03T01:39:20.799026Z","caller":"traceutil/trace.go:171","msg":"trace[1018084123] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"191.482488ms","start":"2026-04-03T01:39:20.607494Z","end":"2026-04-03T01:39:20.798976Z","steps":["trace[1018084123] 'process raft request' (duration: 96.294409ms)","trace[1018084123] 'compare' (duration: 94.714254ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:20.799963Z","caller":"traceutil/trace.go:171","msg":"trace[1708364299] transaction","detail":"{read_only:false; response_revision:4535; number_of_response:1; }","duration":"189.71149ms","start":"2026-04-03T01:39:20.610204Z","end":"2026-04-03T01:39:20.799915Z","steps":["trace[1708364299] 'process raft request' (duration: 188.605589ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:20.800253Z","caller":"traceutil/trace.go:171","msg":"trace[1854253200] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"190.019434ms","start":"2026-04-03T01:39:20.610207Z","end":"2026-04-03T01:39:20.800226Z","steps":["trace[1854253200] 'process raft request' (duration: 189.597649ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:39:20.800662Z","caller":"traceutil/trace.go:171","msg":"trace[195638420] linearizableReadLoop","detail":"{readStateIndex:4731; appliedIndex:4727; }","duration":"150.341673ms","start":"2026-04-03T01:39:20.6503Z","end":"2026-04-03T01:39:20.800642Z","steps":["trace[195638420] 'read index received' (duration: 53.507807ms)","trace[195638420] 'applied index is now lower than readState.Index' (duration: 96.832506ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:39:20.800767Z","caller":"traceutil/trace.go:171","msg":"trace[1061453472] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"165.753006ms","start":"2026-04-03T01:39:20.634991Z","end":"2026-04-03T01:39:20.800744Z","steps":["trace[1061453472] 'process raft request' (duration: 165.153369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:20.800893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.531655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T01:39:20.800947Z","caller":"traceutil/trace.go:171","msg":"trace[653125460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4537; }","duration":"150.699407ms","start":"2026-04-03T01:39:20.650232Z","end":"2026-04-03T01:39:20.800931Z","steps":["trace[653125460] 'agreement among raft nodes before linearized reading' (duration: 150.526616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T01:39:20.801223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.059806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-03T01:39:20.801291Z","caller":"traceutil/trace.go:171","msg":"trace[657906998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4537; }","duration":"142.173947ms","start":"2026-04-03T01:39:20.659097Z","end":"2026-04-03T01:39:20.801271Z","steps":["trace[657906998] 'agreement among raft nodes before linearized reading' (duration: 141.923154ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:40:09.550123Z","caller":"traceutil/trace.go:171","msg":"trace[1148157749] linearizableReadLoop","detail":"{readStateIndex:5330; appliedIndex:5329; }","duration":"118.113876ms","start":"2026-04-03T01:40:09.431962Z","end":"2026-04-03T01:40:09.550076Z","steps":["trace[1148157749] 'read index received' (duration: 79.74103ms)","trace[1148157749] 'applied index is now lower than readState.Index' (duration: 38.371115ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T01:40:09.55038Z","caller":"traceutil/trace.go:171","msg":"trace[1161988542] transaction","detail":"{read_only:false; response_revision:5114; number_of_response:1; }","duration":"135.659055ms","start":"2026-04-03T01:40:09.414656Z","end":"2026-04-03T01:40:09.550315Z","steps":["trace[1161988542] 'process raft request' (duration: 97.162156ms)","trace[1161988542] 'compare' (duration: 38.005106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T01:40:09.550759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.777953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-03T01:40:09.55082Z","caller":"traceutil/trace.go:171","msg":"trace[2144778756] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5114; }","duration":"118.910136ms","start":"2026-04-03T01:40:09.431893Z","end":"2026-04-03T01:40:09.550803Z","steps":["trace[2144778756] 'agreement among raft nodes before linearized reading' (duration: 118.459574ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T01:42:45.072392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3232} {"level":"info","ts":"2026-04-03T01:42:45.160735Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3232,"took":"86.945223ms","hash":1832517352,"current-db-size-bytes":59969536,"current-db-size":"60 MB","current-db-size-in-use-bytes":32227328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-03T01:42:45.160825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1832517352,"revision":3232,"compact-revision":-1}