{"level":"warn","ts":"2026-04-20T04:49:44.409144Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T04:49:44.409462Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T04:49:44.409527Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T04:49:44.409537Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-20T04:49:44.409552Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T04:49:44.410222Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-04-20T04:49:44.4103Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T04:49:44.413481Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.006895ms"} {"level":"info","ts":"2026-04-20T04:49:44.424834Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-04-20T04:49:44.424913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T04:49:44.424938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-04-20T04:49:44.424956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T04:49:44.424966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-04-20T04:49:44.425006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-04-20T04:49:44.430308Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T04:49:44.431601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T04:49:44.432669Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T04:49:44.434704Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T04:49:44.434896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T04:49:44.434925Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T04:49:44.435057Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T04:49:44.435075Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T04:49:44.435791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-04-20T04:49:44.436824Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-04-20T04:49:44.440592Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T04:49:44.440722Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-20T04:49:44.440767Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-04-20T04:49:44.440982Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T04:49:44.441041Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T04:49:44.8253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T04:49:44.825414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T04:49:44.825469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-04-20T04:49:44.825493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-04-20T04:49:44.825506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-20T04:49:44.825523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-04-20T04:49:44.825587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-04-20T04:49:44.82695Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T04:49:44.82697Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:49:44.826996Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:49:44.827026Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:49:44.827361Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T04:49:44.827492Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T04:49:44.828412Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:49:44.828613Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:49:44.828695Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:49:44.830816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T04:49:44.841481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-04-20T04:50:01.345729Z","caller":"traceutil/trace.go:171","msg":"trace[2000690223] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"248.21288ms","start":"2026-04-20T04:50:01.097474Z","end":"2026-04-20T04:50:01.345687Z","steps":["trace[2000690223] 'process raft request' (duration: 247.993564ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:01.353846Z","caller":"traceutil/trace.go:171","msg":"trace[96605083] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"254.735702ms","start":"2026-04-20T04:50:01.099079Z","end":"2026-04-20T04:50:01.353815Z","steps":["trace[96605083] 'process raft request' (duration: 254.592719ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:16.627593Z","caller":"traceutil/trace.go:171","msg":"trace[277887529] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"128.863803ms","start":"2026-04-20T04:50:16.498706Z","end":"2026-04-20T04:50:16.62757Z","steps":["trace[277887529] 'process raft request' (duration: 128.74559ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:16.63023Z","caller":"traceutil/trace.go:171","msg":"trace[1401335769] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"102.74026ms","start":"2026-04-20T04:50:16.527466Z","end":"2026-04-20T04:50:16.630206Z","steps":["trace[1401335769] 'read index received' (duration: 100.089204ms)","trace[1401335769] 'applied index is now lower than readState.Index' (duration: 2.649996ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:50:16.63026Z","caller":"traceutil/trace.go:171","msg":"trace[231896881] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"130.860404ms","start":"2026-04-20T04:50:16.499372Z","end":"2026-04-20T04:50:16.630232Z","steps":["trace[231896881] 'process raft request' (duration: 130.70032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:16.63039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.903275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:50:16.630493Z","caller":"traceutil/trace.go:171","msg":"trace[280019139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"103.060099ms","start":"2026-04-20T04:50:16.527413Z","end":"2026-04-20T04:50:16.630473Z","steps":["trace[280019139] 'agreement among raft nodes before linearized reading' (duration: 102.890254ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:20.459069Z","caller":"traceutil/trace.go:171","msg":"trace[1207716248] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"172.339382ms","start":"2026-04-20T04:50:20.286694Z","end":"2026-04-20T04:50:20.459033Z","steps":["trace[1207716248] 'process raft request' (duration: 171.689065ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:20.931907Z","caller":"traceutil/trace.go:171","msg":"trace[1633809333] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"404.912718ms","start":"2026-04-20T04:50:20.526964Z","end":"2026-04-20T04:50:20.931877Z","steps":["trace[1633809333] 'read index received' (duration: 404.56792ms)","trace[1633809333] 'applied index is now lower than readState.Index' (duration: 343.428µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:50:20.932102Z","caller":"traceutil/trace.go:171","msg":"trace[991688784] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"412.407317ms","start":"2026-04-20T04:50:20.519646Z","end":"2026-04-20T04:50:20.932054Z","steps":["trace[991688784] 'process raft request' (duration: 411.974456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:20.932237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.221286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:50:20.932317Z","caller":"traceutil/trace.go:171","msg":"trace[972657231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"405.394351ms","start":"2026-04-20T04:50:20.526908Z","end":"2026-04-20T04:50:20.932302Z","steps":["trace[972657231] 'agreement among raft nodes before linearized reading' (duration: 405.183826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:20.932408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.715713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T04:50:20.932517Z","caller":"traceutil/trace.go:171","msg":"trace[1910269915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"290.900077ms","start":"2026-04-20T04:50:20.641591Z","end":"2026-04-20T04:50:20.932491Z","steps":["trace[1910269915] 'agreement among raft nodes before linearized reading' (duration: 290.574929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:20.932533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:50:20.519612Z","time spent":"412.577891ms","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:50:20.932662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.504298ms","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-20T04:50:20.932817Z","caller":"traceutil/trace.go:171","msg":"trace[399242938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"290.679812ms","start":"2026-04-20T04:50:20.642106Z","end":"2026-04-20T04:50:20.932785Z","steps":["trace[399242938] 'agreement among raft nodes before linearized reading' (duration: 290.190489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:20.93239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:50:20.526887Z","time spent":"405.466382ms","remote":"127.0.0.1:53304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T04:51:11.468859Z","caller":"traceutil/trace.go:171","msg":"trace[1085560742] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"236.181607ms","start":"2026-04-20T04:51:11.232643Z","end":"2026-04-20T04:51:11.468825Z","steps":["trace[1085560742] 'process raft request' (duration: 235.933161ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:11.482436Z","caller":"traceutil/trace.go:171","msg":"trace[1254331909] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1195; }","duration":"165.485476ms","start":"2026-04-20T04:51:11.316923Z","end":"2026-04-20T04:51:11.482409Z","steps":["trace[1254331909] 'read index received' (duration: 151.699166ms)","trace[1254331909] 'applied index is now lower than readState.Index' (duration: 13.78516ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:11.482548Z","caller":"traceutil/trace.go:171","msg":"trace[38849214] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"248.04809ms","start":"2026-04-20T04:51:11.234461Z","end":"2026-04-20T04:51:11.482509Z","steps":["trace[38849214] 'process raft request' (duration: 247.804325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:11.482602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.651131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:51:11.482639Z","caller":"traceutil/trace.go:171","msg":"trace[563848373] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1165; }","duration":"165.743852ms","start":"2026-04-20T04:51:11.316885Z","end":"2026-04-20T04:51:11.482629Z","steps":["trace[563848373] 'agreement among raft nodes before linearized reading' (duration: 165.63252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:57.289184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.257374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:51:57.289292Z","caller":"traceutil/trace.go:171","msg":"trace[873961613] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1822; }","duration":"160.431538ms","start":"2026-04-20T04:51:57.128837Z","end":"2026-04-20T04:51:57.289268Z","steps":["trace[873961613] 'count revisions from in-memory index tree' (duration: 160.166382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:57.69492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.124036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:51:57.69507Z","caller":"traceutil/trace.go:171","msg":"trace[566244204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1823; }","duration":"161.29128ms","start":"2026-04-20T04:51:57.533705Z","end":"2026-04-20T04:51:57.694996Z","steps":["trace[566244204] 'range keys from in-memory index tree' (duration: 161.041204ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:10.288933Z","caller":"traceutil/trace.go:171","msg":"trace[1776733515] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"198.23365ms","start":"2026-04-20T04:52:10.090665Z","end":"2026-04-20T04:52:10.288899Z","steps":["trace[1776733515] 'process raft request' (duration: 127.673547ms)","trace[1776733515] 'compare' (duration: 70.214395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:52:47.4369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.165003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T04:52:47.437003Z","caller":"traceutil/trace.go:171","msg":"trace[1143122938] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2152; }","duration":"201.435259ms","start":"2026-04-20T04:52:47.235541Z","end":"2026-04-20T04:52:47.436976Z","steps":["trace[1143122938] 'range keys from in-memory index tree' (duration: 200.932077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:47.437005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.857661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:52:47.437105Z","caller":"traceutil/trace.go:171","msg":"trace[422632747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2152; }","duration":"214.014395ms","start":"2026-04-20T04:52:47.223065Z","end":"2026-04-20T04:52:47.437079Z","steps":["trace[422632747] 'count revisions from in-memory index tree' (duration: 213.745529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:48.211908Z","caller":"traceutil/trace.go:171","msg":"trace[1306433283] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"223.634051ms","start":"2026-04-20T04:52:47.988244Z","end":"2026-04-20T04:52:48.211879Z","steps":["trace[1306433283] 'process raft request' (duration: 166.638577ms)","trace[1306433283] 'compare' (duration: 56.81457ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:48.212251Z","caller":"traceutil/trace.go:171","msg":"trace[341841027] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"223.082579ms","start":"2026-04-20T04:52:47.989113Z","end":"2026-04-20T04:52:48.212196Z","steps":["trace[341841027] 'process raft request' (duration: 222.71685ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:48.212243Z","caller":"traceutil/trace.go:171","msg":"trace[229106026] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2234; }","duration":"182.519364ms","start":"2026-04-20T04:52:48.029671Z","end":"2026-04-20T04:52:48.21219Z","steps":["trace[229106026] 'read index received' (duration: 125.216382ms)","trace[229106026] 'applied index is now lower than readState.Index' (duration: 57.300442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:52:48.21242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.733209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-20T04:52:48.212515Z","caller":"traceutil/trace.go:171","msg":"trace[725512880] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2155; }","duration":"182.862682ms","start":"2026-04-20T04:52:48.029637Z","end":"2026-04-20T04:52:48.212499Z","steps":["trace[725512880] 'agreement among raft nodes before linearized reading' (duration: 182.685818ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:36.762386Z","caller":"traceutil/trace.go:171","msg":"trace[112282299] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"151.003928ms","start":"2026-04-20T04:53:36.611353Z","end":"2026-04-20T04:53:36.762357Z","steps":["trace[112282299] 'process raft request' (duration: 150.843465ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:36.768228Z","caller":"traceutil/trace.go:171","msg":"trace[328501892] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"119.076487ms","start":"2026-04-20T04:53:36.649126Z","end":"2026-04-20T04:53:36.768202Z","steps":["trace[328501892] 'process raft request' (duration: 118.903372ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:36.768561Z","caller":"traceutil/trace.go:171","msg":"trace[1435165979] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"112.731988ms","start":"2026-04-20T04:53:36.65581Z","end":"2026-04-20T04:53:36.768542Z","steps":["trace[1435165979] 'process raft request' (duration: 112.341849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:11.168705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.249337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:54:11.168806Z","caller":"traceutil/trace.go:171","msg":"trace[1731230666] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2795; }","duration":"345.406101ms","start":"2026-04-20T04:54:10.823379Z","end":"2026-04-20T04:54:11.168785Z","steps":["trace[1731230666] 'count revisions from in-memory index tree' (duration: 345.149195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:11.168905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:10.823355Z","time spent":"345.535625ms","remote":"127.0.0.1:54052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-20T04:54:11.169318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.312699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:54:11.169649Z","caller":"traceutil/trace.go:171","msg":"trace[648517419] linearizableReadLoop","detail":"{readStateIndex:2904; appliedIndex:2901; }","duration":"230.513884ms","start":"2026-04-20T04:54:10.939117Z","end":"2026-04-20T04:54:11.169631Z","steps":["trace[648517419] 'read index received' (duration: 16.550689ms)","trace[648517419] 'applied index is now lower than readState.Index' (duration: 213.962295ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:11.16965Z","caller":"traceutil/trace.go:171","msg":"trace[1988691489] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"266.08034ms","start":"2026-04-20T04:54:10.903547Z","end":"2026-04-20T04:54:11.169627Z","steps":["trace[1988691489] 'process raft request' (duration: 52.095474ms)","trace[1988691489] 'compare' (duration: 212.902699ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:11.169834Z","caller":"traceutil/trace.go:171","msg":"trace[1619359979] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"252.661027ms","start":"2026-04-20T04:54:10.917136Z","end":"2026-04-20T04:54:11.169797Z","steps":["trace[1619359979] 'process raft request' (duration: 252.422211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:11.170016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.905694ms","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-20T04:54:11.170056Z","caller":"traceutil/trace.go:171","msg":"trace[161639486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2798; }","duration":"230.988486ms","start":"2026-04-20T04:54:10.939057Z","end":"2026-04-20T04:54:11.170046Z","steps":["trace[161639486] 'agreement among raft nodes before linearized reading' (duration: 230.851653ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:11.170039Z","caller":"traceutil/trace.go:171","msg":"trace[1403908556] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"252.936084ms","start":"2026-04-20T04:54:10.917008Z","end":"2026-04-20T04:54:11.169944Z","steps":["trace[1403908556] 'process raft request' (duration: 252.462362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:11.178965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.145489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T04:54:11.179047Z","caller":"traceutil/trace.go:171","msg":"trace[1194178922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2799; }","duration":"214.288652ms","start":"2026-04-20T04:54:10.964741Z","end":"2026-04-20T04:54:11.179029Z","steps":["trace[1194178922] 'agreement among raft nodes before linearized reading' (duration: 213.928934ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:58:02.384776Z","caller":"traceutil/trace.go:171","msg":"trace[1869798613] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"111.032272ms","start":"2026-04-20T04:58:02.273711Z","end":"2026-04-20T04:58:02.384744Z","steps":["trace[1869798613] 'process raft request' (duration: 107.769876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:58:05.269448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.338354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:58:05.269629Z","caller":"traceutil/trace.go:171","msg":"trace[8070951] linearizableReadLoop","detail":"{readStateIndex:5128; appliedIndex:5127; }","duration":"145.572212ms","start":"2026-04-20T04:58:05.124032Z","end":"2026-04-20T04:58:05.269604Z","steps":["trace[8070951] 'read index received' (duration: 26.950771ms)","trace[8070951] 'applied index is now lower than readState.Index' (duration: 118.619761ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:58:05.269754Z","caller":"traceutil/trace.go:171","msg":"trace[1467446363] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"188.784755ms","start":"2026-04-20T04:58:05.080917Z","end":"2026-04-20T04:58:05.269701Z","steps":["trace[1467446363] 'process raft request' (duration: 70.054172ms)","trace[1467446363] 'compare' (duration: 118.115379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:58:05.269882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.844899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-80aa58de-1c46-4129-8873-e03389e543e6\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-20T04:58:05.26992Z","caller":"traceutil/trace.go:171","msg":"trace[1522657301] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-80aa58de-1c46-4129-8873-e03389e543e6; range_end:; response_count:1; response_revision:4939; }","duration":"145.947861ms","start":"2026-04-20T04:58:05.12396Z","end":"2026-04-20T04:58:05.269908Z","steps":["trace[1522657301] 'agreement among raft nodes before linearized reading' (duration: 145.702695ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:58:17.756401Z","caller":"traceutil/trace.go:171","msg":"trace[1153741006] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"323.102782ms","start":"2026-04-20T04:58:17.433273Z","end":"2026-04-20T04:58:17.756376Z","steps":["trace[1153741006] 'process raft request' (duration: 322.233022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:58:17.756559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:58:17.433262Z","time spent":"323.220724ms","remote":"127.0.0.1:53478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8040,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:58:24.931344Z","caller":"traceutil/trace.go:171","msg":"trace[1303447194] linearizableReadLoop","detail":"{readStateIndex:5564; appliedIndex:5563; }","duration":"335.881851ms","start":"2026-04-20T04:58:24.595413Z","end":"2026-04-20T04:58:24.931295Z","steps":["trace[1303447194] 'read index received' (duration: 309.871002ms)","trace[1303447194] 'applied index is now lower than readState.Index' (duration: 26.008709ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:58:24.931454Z","caller":"traceutil/trace.go:171","msg":"trace[1155566109] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"359.253359ms","start":"2026-04-20T04:58:24.572164Z","end":"2026-04-20T04:58:24.931418Z","steps":["trace[1155566109] 'process raft request' (duration: 333.146928ms)","trace[1155566109] 'compare' (duration: 25.748493ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:58:24.931815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.382925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a7f7dc53174e53\" ","response":"range_response_count:1 size:1012"} {"level":"warn","ts":"2026-04-20T04:58:24.931815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.227526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-891d2b54-fcc0-4572-93d8-e882bc67ffe0\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-04-20T04:58:24.931894Z","caller":"traceutil/trace.go:171","msg":"trace[1862452502] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a7f7dc53174e53; range_end:; response_count:1; response_revision:5363; }","duration":"336.528168ms","start":"2026-04-20T04:58:24.595343Z","end":"2026-04-20T04:58:24.931871Z","steps":["trace[1862452502] 'agreement among raft nodes before linearized reading' (duration: 336.213079ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:58:24.931923Z","caller":"traceutil/trace.go:171","msg":"trace[558931731] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-891d2b54-fcc0-4572-93d8-e882bc67ffe0; range_end:; response_count:1; response_revision:5363; }","duration":"233.355449ms","start":"2026-04-20T04:58:24.698543Z","end":"2026-04-20T04:58:24.931898Z","steps":["trace[558931731] 'agreement among raft nodes before linearized reading' (duration: 233.081292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:58:24.931948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:58:24.595318Z","time spent":"336.614319ms","remote":"127.0.0.1:53370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":1035,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a7f7dc53174e53\" "} {"level":"warn","ts":"2026-04-20T04:58:24.931803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.731407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:58:24.93207Z","caller":"traceutil/trace.go:171","msg":"trace[222751742] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:5363; }","duration":"219.042835ms","start":"2026-04-20T04:58:24.713004Z","end":"2026-04-20T04:58:24.932047Z","steps":["trace[222751742] 'agreement among raft nodes before linearized reading' (duration: 218.721057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:58:24.932016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:58:24.57215Z","time spent":"359.604488ms","remote":"127.0.0.1:53570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:59:44.859952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2981} {"level":"info","ts":"2026-04-20T04:59:44.940572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2981,"took":"78.013082ms","hash":1404153568,"current-db-size-bytes":58109952,"current-db-size":"58 MB","current-db-size-in-use-bytes":33693696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T04:59:44.940661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1404153568,"revision":2981,"compact-revision":-1} {"level":"info","ts":"2026-04-20T05:04:37.913286Z","caller":"traceutil/trace.go:171","msg":"trace[253271875] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"108.486787ms","start":"2026-04-20T05:04:37.804758Z","end":"2026-04-20T05:04:37.913245Z","steps":["trace[253271875] 'process raft request' (duration: 108.215351ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:04:44.869253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6074} {"level":"info","ts":"2026-04-20T05:04:44.965594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6074,"took":"94.448565ms","hash":351243317,"current-db-size-bytes":58109952,"current-db-size":"58 MB","current-db-size-in-use-bytes":23556096,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-20T05:04:44.965665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":351243317,"revision":6074,"compact-revision":2981} {"level":"info","ts":"2026-04-20T05:04:49.246549Z","caller":"traceutil/trace.go:171","msg":"trace[1754324525] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"201.037464ms","start":"2026-04-20T05:04:49.045468Z","end":"2026-04-20T05:04:49.246506Z","steps":["trace[1754324525] 'process raft request' (duration: 151.369543ms)","trace[1754324525] 'compare' (duration: 49.551428ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:04:51.381656Z","caller":"traceutil/trace.go:171","msg":"trace[942331475] transaction","detail":"{read_only:false; response_revision:8297; number_of_response:1; }","duration":"126.890676ms","start":"2026-04-20T05:04:51.254746Z","end":"2026-04-20T05:04:51.381636Z","steps":["trace[942331475] 'process raft request' (duration: 126.448074ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:15.367149Z","caller":"traceutil/trace.go:171","msg":"trace[674225184] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"104.202647ms","start":"2026-04-20T05:05:15.262908Z","end":"2026-04-20T05:05:15.36711Z","steps":["trace[674225184] 'process raft request' (duration: 101.850821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:18.792241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.709598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:05:18.792347Z","caller":"traceutil/trace.go:171","msg":"trace[597576817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8499; }","duration":"263.874182ms","start":"2026-04-20T05:05:18.528448Z","end":"2026-04-20T05:05:18.792323Z","steps":["trace[597576817] 'range keys from in-memory index tree' (duration: 263.635926ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:22.445562Z","caller":"traceutil/trace.go:171","msg":"trace[2107768397] linearizableReadLoop","detail":"{readStateIndex:8820; appliedIndex:8819; }","duration":"269.193078ms","start":"2026-04-20T05:05:22.176334Z","end":"2026-04-20T05:05:22.445527Z","steps":["trace[2107768397] 'read index received' (duration: 268.977893ms)","trace[2107768397] 'applied index is now lower than readState.Index' (duration: 214.135µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:05:22.445709Z","caller":"traceutil/trace.go:171","msg":"trace[1493875020] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"271.013721ms","start":"2026-04-20T05:05:22.174682Z","end":"2026-04-20T05:05:22.445695Z","steps":["trace[1493875020] 'process raft request' (duration: 270.645412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:22.44592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.564554ms","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-20T05:05:22.446022Z","caller":"traceutil/trace.go:171","msg":"trace[1936407528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8516; }","duration":"199.705956ms","start":"2026-04-20T05:05:22.246292Z","end":"2026-04-20T05:05:22.445998Z","steps":["trace[1936407528] 'agreement among raft nodes before linearized reading' (duration: 199.472691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:22.44604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.791449ms","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-20T05:05:22.446092Z","caller":"traceutil/trace.go:171","msg":"trace[1751493886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8516; }","duration":"175.86977ms","start":"2026-04-20T05:05:22.270204Z","end":"2026-04-20T05:05:22.446074Z","steps":["trace[1751493886] 'agreement among raft nodes before linearized reading' (duration: 175.747446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:22.446076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.737072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:05:22.446162Z","caller":"traceutil/trace.go:171","msg":"trace[1122611719] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8516; }","duration":"269.875125ms","start":"2026-04-20T05:05:22.176273Z","end":"2026-04-20T05:05:22.446148Z","steps":["trace[1122611719] 'agreement among raft nodes before linearized reading' (duration: 269.73092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:22.446167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.556381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:05:22.446302Z","caller":"traceutil/trace.go:171","msg":"trace[1146099035] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8516; }","duration":"116.779657ms","start":"2026-04-20T05:05:22.329494Z","end":"2026-04-20T05:05:22.446274Z","steps":["trace[1146099035] 'agreement among raft nodes before linearized reading' (duration: 116.409807ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:31.876813Z","caller":"traceutil/trace.go:171","msg":"trace[552974607] linearizableReadLoop","detail":"{readStateIndex:8921; appliedIndex:8920; }","duration":"225.504481ms","start":"2026-04-20T05:05:31.651286Z","end":"2026-04-20T05:05:31.87679Z","steps":["trace[552974607] 'read index received' (duration: 225.329296ms)","trace[552974607] 'applied index is now lower than readState.Index' (duration: 173.985µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:05:31.876894Z","caller":"traceutil/trace.go:171","msg":"trace[1082521527] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"230.676182ms","start":"2026-04-20T05:05:31.646174Z","end":"2026-04-20T05:05:31.87685Z","steps":["trace[1082521527] 'process raft request' (duration: 230.460117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:31.877194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.875189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9x9qg\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-20T05:05:31.8773Z","caller":"traceutil/trace.go:171","msg":"trace[1825641437] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9x9qg; range_end:; response_count:1; response_revision:8611; }","duration":"226.023282ms","start":"2026-04-20T05:05:31.651258Z","end":"2026-04-20T05:05:31.877281Z","steps":["trace[1825641437] 'agreement among raft nodes before linearized reading' (duration: 225.687644ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:31.990867Z","caller":"traceutil/trace.go:171","msg":"trace[349150783] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"266.824403ms","start":"2026-04-20T05:05:31.724016Z","end":"2026-04-20T05:05:31.99084Z","steps":["trace[349150783] 'process raft request' (duration: 266.585137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:31.991276Z","caller":"traceutil/trace.go:171","msg":"trace[1454713091] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8921; }","duration":"114.366278ms","start":"2026-04-20T05:05:31.876882Z","end":"2026-04-20T05:05:31.991248Z","steps":["trace[1454713091] 'read index received' (duration: 113.657122ms)","trace[1454713091] 'applied index is now lower than readState.Index' (duration: 708.196µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:05:31.991376Z","caller":"traceutil/trace.go:171","msg":"trace[692070204] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"165.35241ms","start":"2026-04-20T05:05:31.826012Z","end":"2026-04-20T05:05:31.991365Z","steps":["trace[692070204] 'process raft request' (duration: 165.179956ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:31.991387Z","caller":"traceutil/trace.go:171","msg":"trace[2092240993] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"171.511757ms","start":"2026-04-20T05:05:31.819844Z","end":"2026-04-20T05:05:31.991355Z","steps":["trace[2092240993] 'process raft request' (duration: 171.20324ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:31.991262Z","caller":"traceutil/trace.go:171","msg":"trace[366303644] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"185.92266ms","start":"2026-04-20T05:05:31.805273Z","end":"2026-04-20T05:05:31.991196Z","steps":["trace[366303644] 'process raft request' (duration: 185.51256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:31.991626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.740251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-20T05:05:31.991725Z","caller":"traceutil/trace.go:171","msg":"trace[403599472] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8615; }","duration":"245.899135ms","start":"2026-04-20T05:05:31.745805Z","end":"2026-04-20T05:05:31.991704Z","steps":["trace[403599472] 'agreement among raft nodes before linearized reading' (duration: 245.586358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:31.992014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.242323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228741"} {"level":"info","ts":"2026-04-20T05:05:31.992069Z","caller":"traceutil/trace.go:171","msg":"trace[1391406411] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8615; }","duration":"108.354235ms","start":"2026-04-20T05:05:31.883703Z","end":"2026-04-20T05:05:31.992058Z","steps":["trace[1391406411] 'agreement among raft nodes before linearized reading' (duration: 107.71093ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:32.595036Z","caller":"traceutil/trace.go:171","msg":"trace[1336357121] transaction","detail":"{read_only:false; response_revision:8618; number_of_response:1; }","duration":"151.321506ms","start":"2026-04-20T05:05:32.443689Z","end":"2026-04-20T05:05:32.59501Z","steps":["trace[1336357121] 'process raft request' (duration: 151.108661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:32.801381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.448648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:05:32.80153Z","caller":"traceutil/trace.go:171","msg":"trace[1187601092] linearizableReadLoop","detail":"{readStateIndex:8929; appliedIndex:8927; }","duration":"272.386484ms","start":"2026-04-20T05:05:32.529123Z","end":"2026-04-20T05:05:32.801509Z","steps":["trace[1187601092] 'read index received' (duration: 65.648931ms)","trace[1187601092] 'applied index is now lower than readState.Index' (duration: 206.736053ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:05:32.8016Z","caller":"traceutil/trace.go:171","msg":"trace[1861725030] transaction","detail":"{read_only:false; response_revision:8619; number_of_response:1; }","duration":"319.945624ms","start":"2026-04-20T05:05:32.481631Z","end":"2026-04-20T05:05:32.801577Z","steps":["trace[1861725030] 'process raft request' (duration: 118.176708ms)","trace[1861725030] 'compare' (duration: 201.322936ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:05:32.801718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.606189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T05:05:32.801752Z","caller":"traceutil/trace.go:171","msg":"trace[884798057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8619; }","duration":"272.690021ms","start":"2026-04-20T05:05:32.52905Z","end":"2026-04-20T05:05:32.80174Z","steps":["trace[884798057] 'agreement among raft nodes before linearized reading' (duration: 272.544507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:05:32.801871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.045275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-20T05:05:32.80172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:05:32.481608Z","time spent":"320.050187ms","remote":"127.0.0.1:53570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:05:32.801969Z","caller":"traceutil/trace.go:171","msg":"trace[803119378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8619; }","duration":"203.189229ms","start":"2026-04-20T05:05:32.598758Z","end":"2026-04-20T05:05:32.801947Z","steps":["trace[803119378] 'agreement among raft nodes before linearized reading' (duration: 202.932663ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:32.927918Z","caller":"traceutil/trace.go:171","msg":"trace[1589089382] linearizableReadLoop","detail":"{readStateIndex:8931; appliedIndex:8929; }","duration":"120.886163ms","start":"2026-04-20T05:05:32.807006Z","end":"2026-04-20T05:05:32.927892Z","steps":["trace[1589089382] 'read index received' (duration: 10.231093ms)","trace[1589089382] 'applied index is now lower than readState.Index' (duration: 110.65429ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:05:32.928003Z","caller":"traceutil/trace.go:171","msg":"trace[22303033] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"121.19357ms","start":"2026-04-20T05:05:32.806772Z","end":"2026-04-20T05:05:32.927966Z","steps":["trace[22303033] 'process raft request' (duration: 99.005212ms)","trace[22303033] 'compare' (duration: 21.980413ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:05:32.928149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.124218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19381"} {"level":"info","ts":"2026-04-20T05:05:32.928186Z","caller":"traceutil/trace.go:171","msg":"trace[1143882311] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8621; }","duration":"121.202881ms","start":"2026-04-20T05:05:32.806972Z","end":"2026-04-20T05:05:32.928175Z","steps":["trace[1143882311] 'agreement among raft nodes before linearized reading' (duration: 121.022056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:05:33.050365Z","caller":"traceutil/trace.go:171","msg":"trace[605215523] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"103.253095ms","start":"2026-04-20T05:05:32.947042Z","end":"2026-04-20T05:05:33.050295Z","steps":["trace[605215523] 'process raft request' (duration: 86.885916ms)","trace[605215523] 'compare' (duration: 16.143033ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:06:07.696061Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243a.wal"} {"level":"info","ts":"2026-04-20T05:06:49.414612Z","caller":"traceutil/trace.go:171","msg":"trace[918199194] linearizableReadLoop","detail":"{readStateIndex:9693; appliedIndex:9692; }","duration":"150.443279ms","start":"2026-04-20T05:06:49.264136Z","end":"2026-04-20T05:06:49.414579Z","steps":["trace[918199194] 'read index received' (duration: 107.21734ms)","trace[918199194] 'applied index is now lower than readState.Index' (duration: 43.224119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:06:49.414844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.681485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:06:49.414911Z","caller":"traceutil/trace.go:171","msg":"trace[1022798742] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:9361; }","duration":"150.821579ms","start":"2026-04-20T05:06:49.264074Z","end":"2026-04-20T05:06:49.414895Z","steps":["trace[1022798742] 'agreement among raft nodes before linearized reading' (duration: 150.694416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:06:49.415068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.664953ms","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-20T05:06:49.415129Z","caller":"traceutil/trace.go:171","msg":"trace[810047117] transaction","detail":"{read_only:false; response_revision:9361; number_of_response:1; }","duration":"217.28408ms","start":"2026-04-20T05:06:49.19783Z","end":"2026-04-20T05:06:49.415114Z","steps":["trace[810047117] 'process raft request' (duration: 173.58939ms)","trace[810047117] 'compare' (duration: 42.612034ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:06:49.415143Z","caller":"traceutil/trace.go:171","msg":"trace[1080300236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9361; }","duration":"107.780785ms","start":"2026-04-20T05:06:49.307349Z","end":"2026-04-20T05:06:49.415129Z","steps":["trace[1080300236] 'agreement among raft nodes before linearized reading' (duration: 107.488358ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:07:28.407513Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T05:07:28.412685Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T05:07:28.412761Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T05:09:44.876037Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8265} {"level":"info","ts":"2026-04-20T05:09:44.95602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8265,"took":"77.71853ms","hash":2946804113,"current-db-size-bytes":58109952,"current-db-size":"58 MB","current-db-size-in-use-bytes":24850432,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T05:09:44.956102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2946804113,"revision":8265,"compact-revision":6074} {"level":"info","ts":"2026-04-20T05:14:44.88227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10656} {"level":"info","ts":"2026-04-20T05:14:44.966023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10656,"took":"81.419381ms","hash":2674837660,"current-db-size-bytes":58109952,"current-db-size":"58 MB","current-db-size-in-use-bytes":20942848,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-20T05:14:44.966103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2674837660,"revision":10656,"compact-revision":8265}