{"level":"warn","ts":"2026-10-13T05:36:02.177899Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T05:36:02.178462Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.159: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://162.253.55.159:2380","--initial-cluster=instance=https://162.253.55.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.159: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-10-13T05:36:02.178703Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T05:36:02.178765Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-10-13T05:36:02.178869Z","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-10-13T05:36:02.179876Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"]} {"level":"info","ts":"2026-10-13T05:36:02.181199Z","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://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.159: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-10-13T05:36:02.190225Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.594783ms"} {"level":"info","ts":"2026-10-13T05:36:02.197172Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"94bfbb0e034a3ac2","cluster-id":"9676abcf1742949"} {"level":"info","ts":"2026-10-13T05:36:02.197227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T05:36:02.197243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 0"} {"level":"info","ts":"2026-10-13T05:36:02.197252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 94bfbb0e034a3ac2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T05:36:02.197257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became follower at term 1"} {"level":"info","ts":"2026-10-13T05:36:02.197694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"warn","ts":"2026-10-13T05:36:02.206593Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T05:36:02.207863Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T05:36:02.21039Z","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-10-13T05:36:02.212461Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"94bfbb0e034a3ac2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T05:36:02.214781Z","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-10-13T05:36:02.215134Z","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-10-13T05:36:02.215145Z","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-10-13T05:36:02.215166Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"94bfbb0e034a3ac2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T05:36:02.215644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 switched to configuration voters=(10718491307024202434)"} {"level":"info","ts":"2026-10-13T05:36:02.215733Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","added-peer-id":"94bfbb0e034a3ac2","added-peer-peer-urls":["https://162.253.55.159:2380"]} {"level":"info","ts":"2026-10-13T05:36:02.217198Z","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-10-13T05:36:02.217263Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-10-13T05:36:02.217296Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.159:2380"} {"level":"info","ts":"2026-10-13T05:36:02.217904Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"94bfbb0e034a3ac2","initial-advertise-peer-urls":["https://162.253.55.159:2380"],"listen-peer-urls":["https://162.253.55.159:2380"],"advertise-client-urls":["https://162.253.55.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T05:36:02.21793Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T05:36:02.797936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T05:36:02.797994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T05:36:02.798024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgPreVoteResp from 94bfbb0e034a3ac2 at term 1"} {"level":"info","ts":"2026-10-13T05:36:02.798045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became candidate at term 2"} {"level":"info","ts":"2026-10-13T05:36:02.798056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 received MsgVoteResp from 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-10-13T05:36:02.798071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"94bfbb0e034a3ac2 became leader at term 2"} {"level":"info","ts":"2026-10-13T05:36:02.798091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 94bfbb0e034a3ac2 elected leader 94bfbb0e034a3ac2 at term 2"} {"level":"info","ts":"2026-10-13T05:36:02.799Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"94bfbb0e034a3ac2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.159:2379]}","request-path":"/0/members/94bfbb0e034a3ac2/attributes","cluster-id":"9676abcf1742949","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T05:36:02.799042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T05:36:02.799176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T05:36:02.799204Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T05:36:02.800145Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9676abcf1742949","local-member-id":"94bfbb0e034a3ac2","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T05:36:02.800226Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T05:36:02.80025Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T05:36:02.80158Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T05:36:02.801644Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T05:36:02.802837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.159:2379"} {"level":"info","ts":"2026-10-13T05:36:02.803202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-13T05:36:18.558313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.357548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T05:36:18.558469Z","caller":"traceutil/trace.go:171","msg":"trace[1753688031] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"456.006338ms","start":"2026-10-13T05:36:18.102425Z","end":"2026-10-13T05:36:18.558431Z","steps":["trace[1753688031] 'read index received' (duration: 66.836941ms)","trace[1753688031] 'applied index is now lower than readState.Index' (duration: 389.168007ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T05:36:18.558615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.190652ms","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-10-13T05:36:18.55865Z","caller":"traceutil/trace.go:171","msg":"trace[269952924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"456.257364ms","start":"2026-10-13T05:36:18.102381Z","end":"2026-10-13T05:36:18.558638Z","steps":["trace[269952924] 'agreement among raft nodes before linearized reading' (duration: 456.182132ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:36:18.558625Z","caller":"traceutil/trace.go:171","msg":"trace[2072868910] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"514.294168ms","start":"2026-10-13T05:36:18.044276Z","end":"2026-10-13T05:36:18.55857Z","steps":["trace[2072868910] 'process raft request' (duration: 125.040159ms)","trace[2072868910] 'compare' (duration: 388.215115ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T05:36:18.558687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T05:36:18.102357Z","time spent":"456.322045ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T05:36:18.558752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.662802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T05:36:18.558808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T05:36:18.044247Z","time spent":"514.474383ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T05:36:18.558832Z","caller":"traceutil/trace.go:171","msg":"trace[1035484362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"307.847956ms","start":"2026-10-13T05:36:18.250963Z","end":"2026-10-13T05:36:18.558811Z","steps":["trace[1035484362] 'agreement among raft nodes before linearized reading' (duration: 307.670202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:36:18.558879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T05:36:18.250941Z","time spent":"307.925287ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T05:36:18.985453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.165187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T05:36:18.985737Z","caller":"traceutil/trace.go:171","msg":"trace[888873388] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"405.660283ms","start":"2026-10-13T05:36:18.580054Z","end":"2026-10-13T05:36:18.985714Z","steps":["trace[888873388] 'read index received' (duration: 130.053646ms)","trace[888873388] 'applied index is now lower than readState.Index' (duration: 275.605277ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T05:36:18.985824Z","caller":"traceutil/trace.go:171","msg":"trace[727417483] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"422.340865ms","start":"2026-10-13T05:36:18.563438Z","end":"2026-10-13T05:36:18.985778Z","steps":["trace[727417483] 'process raft request' (duration: 422.161131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:36:18.985974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.91873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-13T05:36:18.986007Z","caller":"traceutil/trace.go:171","msg":"trace[109509247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:369; }","duration":"405.988371ms","start":"2026-10-13T05:36:18.580011Z","end":"2026-10-13T05:36:18.985999Z","steps":["trace[109509247] 'agreement among raft nodes before linearized reading' (duration: 405.902059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:36:18.986024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T05:36:18.579995Z","time spent":"406.026132ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T05:36:18.986021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T05:36:18.563422Z","time spent":"422.50014ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T05:36:18.986032Z","caller":"traceutil/trace.go:171","msg":"trace[1965476916] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"422.455238ms","start":"2026-10-13T05:36:18.563437Z","end":"2026-10-13T05:36:18.985893Z","steps":["trace[1965476916] 'process raft request' (duration: 146.735638ms)","trace[1965476916] 'compare' (duration: 274.961643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T05:36:18.98625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.949582ms","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-10-13T05:36:18.986328Z","caller":"traceutil/trace.go:171","msg":"trace[173498609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:369; }","duration":"306.059645ms","start":"2026-10-13T05:36:18.680244Z","end":"2026-10-13T05:36:18.986304Z","steps":["trace[173498609] 'agreement among raft nodes before linearized reading' (duration: 305.86556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:36:18.98638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T05:36:18.68023Z","time spent":"306.137876ms","remote":"127.0.0.1:59058","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-10-13T05:36:18.986339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T05:36:18.563412Z","time spent":"422.743346ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T05:36:50.177665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.053937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qsnn6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-13T05:36:50.177757Z","caller":"traceutil/trace.go:171","msg":"trace[9321932] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qsnn6; range_end:; response_count:1; response_revision:589; }","duration":"122.190119ms","start":"2026-10-13T05:36:50.055547Z","end":"2026-10-13T05:36:50.177737Z","steps":["trace[9321932] 'range keys from in-memory index tree' (duration: 121.878474ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:36:50.905728Z","caller":"traceutil/trace.go:171","msg":"trace[216693579] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"281.900392ms","start":"2026-10-13T05:36:50.623805Z","end":"2026-10-13T05:36:50.905706Z","steps":["trace[216693579] 'process raft request' (duration: 281.28209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:37:05.131248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.13385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T05:37:05.131631Z","caller":"traceutil/trace.go:171","msg":"trace[271979505] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:759; }","duration":"248.582407ms","start":"2026-10-13T05:37:04.88303Z","end":"2026-10-13T05:37:05.131613Z","steps":["trace[271979505] 'count revisions from in-memory index tree' (duration: 248.053318ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:37:36.709962Z","caller":"traceutil/trace.go:171","msg":"trace[1129311826] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"207.993135ms","start":"2026-10-13T05:37:36.501935Z","end":"2026-10-13T05:37:36.709929Z","steps":["trace[1129311826] 'process raft request' (duration: 108.838172ms)","trace[1129311826] 'compare' (duration: 98.934669ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T05:38:24.506135Z","caller":"traceutil/trace.go:171","msg":"trace[12389605] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"118.264447ms","start":"2026-10-13T05:38:24.387851Z","end":"2026-10-13T05:38:24.506115Z","steps":["trace[12389605] 'read index received' (duration: 118.010434ms)","trace[12389605] 'applied index is now lower than readState.Index' (duration: 226.902µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T05:38:24.506167Z","caller":"traceutil/trace.go:171","msg":"trace[655063061] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"198.903661ms","start":"2026-10-13T05:38:24.307219Z","end":"2026-10-13T05:38:24.506122Z","steps":["trace[655063061] 'process raft request' (duration: 198.686798ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:38:24.50636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.440449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T05:38:24.506397Z","caller":"traceutil/trace.go:171","msg":"trace[1334948136] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1838; }","duration":"118.577711ms","start":"2026-10-13T05:38:24.387809Z","end":"2026-10-13T05:38:24.506387Z","steps":["trace[1334948136] 'agreement among raft nodes before linearized reading' (duration: 118.442309ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:38:24.633802Z","caller":"traceutil/trace.go:171","msg":"trace[1125980749] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"116.666077ms","start":"2026-10-13T05:38:24.517081Z","end":"2026-10-13T05:38:24.633747Z","steps":["trace[1125980749] 'process raft request' (duration: 97.87449ms)","trace[1125980749] 'compare' (duration: 18.592465ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T05:38:54.020768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.88518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T05:38:54.021233Z","caller":"traceutil/trace.go:171","msg":"trace[1787227438] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"124.470014ms","start":"2026-10-13T05:38:53.896744Z","end":"2026-10-13T05:38:54.021214Z","steps":["trace[1787227438] 'compare' (duration: 114.708628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:38:54.042225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.983062ms","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-10-13T05:38:54.04234Z","caller":"traceutil/trace.go:171","msg":"trace[422411005] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2058; }","duration":"105.114114ms","start":"2026-10-13T05:38:53.937207Z","end":"2026-10-13T05:38:54.042321Z","steps":["trace[422411005] 'agreement among raft nodes before linearized reading' (duration: 104.897382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:38:54.042216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.86733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-13T05:38:54.042547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.66179ms","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-10-13T05:38:54.042689Z","caller":"traceutil/trace.go:171","msg":"trace[1594364308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2058; }","duration":"105.880193ms","start":"2026-10-13T05:38:53.936774Z","end":"2026-10-13T05:38:54.042654Z","steps":["trace[1594364308] 'agreement among raft nodes before linearized reading' (duration: 105.440858ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:38:54.042582Z","caller":"traceutil/trace.go:171","msg":"trace[401621670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2058; }","duration":"105.198205ms","start":"2026-10-13T05:38:53.937308Z","end":"2026-10-13T05:38:54.042506Z","steps":["trace[401621670] 'agreement among raft nodes before linearized reading' (duration: 104.778651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:38:54.043259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.935031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-13T05:38:54.043319Z","caller":"traceutil/trace.go:171","msg":"trace[1948872402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2058; }","duration":"110.029082ms","start":"2026-10-13T05:38:53.93327Z","end":"2026-10-13T05:38:54.043299Z","steps":["trace[1948872402] 'agreement among raft nodes before linearized reading' (duration: 108.767807ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:38:54.155849Z","caller":"traceutil/trace.go:171","msg":"trace[1106486597] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"130.611307ms","start":"2026-10-13T05:38:54.025204Z","end":"2026-10-13T05:38:54.155816Z","steps":["trace[1106486597] 'process raft request' (duration: 114.416784ms)","trace[1106486597] 'compare' (duration: 15.929449ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T05:38:54.155985Z","caller":"traceutil/trace.go:171","msg":"trace[1234719382] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"108.915538ms","start":"2026-10-13T05:38:54.046983Z","end":"2026-10-13T05:38:54.155899Z","steps":["trace[1234719382] 'process raft request' (duration: 108.725235ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:38:54.155998Z","caller":"traceutil/trace.go:171","msg":"trace[984545328] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"108.916208ms","start":"2026-10-13T05:38:54.047042Z","end":"2026-10-13T05:38:54.155958Z","steps":["trace[984545328] 'process raft request' (duration: 108.730705ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:38:54.156634Z","caller":"traceutil/trace.go:171","msg":"trace[278088365] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"108.163379ms","start":"2026-10-13T05:38:54.047735Z","end":"2026-10-13T05:38:54.155899Z","steps":["trace[278088365] 'process raft request' (duration: 108.090518ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:39:00.224405Z","caller":"traceutil/trace.go:171","msg":"trace[656564276] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"186.361454ms","start":"2026-10-13T05:39:00.038022Z","end":"2026-10-13T05:39:00.224383Z","steps":["trace[656564276] 'process raft request' (duration: 149.113909ms)","trace[656564276] 'compare' (duration: 36.996087ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T05:39:00.227664Z","caller":"traceutil/trace.go:171","msg":"trace[1604275190] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2165; }","duration":"144.414605ms","start":"2026-10-13T05:39:00.083227Z","end":"2026-10-13T05:39:00.227642Z","steps":["trace[1604275190] 'read index received' (duration: 103.920963ms)","trace[1604275190] 'applied index is now lower than readState.Index' (duration: 40.492432ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T05:39:00.227725Z","caller":"traceutil/trace.go:171","msg":"trace[1382767000] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"170.380593ms","start":"2026-10-13T05:39:00.057328Z","end":"2026-10-13T05:39:00.227709Z","steps":["trace[1382767000] 'process raft request' (duration: 170.208697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:39:00.227883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.639662ms","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-10-13T05:39:00.227945Z","caller":"traceutil/trace.go:171","msg":"trace[1389082239] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2090; }","duration":"144.722824ms","start":"2026-10-13T05:39:00.083187Z","end":"2026-10-13T05:39:00.22791Z","steps":["trace[1389082239] 'agreement among raft nodes before linearized reading' (duration: 144.567659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T05:39:00.651891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.954245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T05:39:00.652001Z","caller":"traceutil/trace.go:171","msg":"trace[1518132945] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2094; }","duration":"120.120011ms","start":"2026-10-13T05:39:00.531864Z","end":"2026-10-13T05:39:00.651984Z","steps":["trace[1518132945] 'count revisions from in-memory index tree' (duration: 119.831091ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:39:00.748472Z","caller":"traceutil/trace.go:171","msg":"trace[712944598] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"108.023057ms","start":"2026-10-13T05:39:00.640418Z","end":"2026-10-13T05:39:00.748441Z","steps":["trace[712944598] 'process raft request' (duration: 107.039496ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:44:14.689879Z","caller":"traceutil/trace.go:171","msg":"trace[368854791] transaction","detail":"{read_only:false; response_revision:4007; number_of_response:1; }","duration":"107.814659ms","start":"2026-10-13T05:44:14.582028Z","end":"2026-10-13T05:44:14.689843Z","steps":["trace[368854791] 'process raft request' (duration: 85.480056ms)","trace[368854791] 'compare' (duration: 21.758425ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T05:45:13.084118Z","caller":"traceutil/trace.go:171","msg":"trace[339689525] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"123.174873ms","start":"2026-10-13T05:45:12.960917Z","end":"2026-10-13T05:45:13.084091Z","steps":["trace[339689525] 'process raft request' (duration: 122.697176ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:45:13.313782Z","caller":"traceutil/trace.go:171","msg":"trace[1047792846] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"223.433718ms","start":"2026-10-13T05:45:13.09032Z","end":"2026-10-13T05:45:13.313754Z","steps":["trace[1047792846] 'process raft request' (duration: 205.022835ms)","trace[1047792846] 'compare' (duration: 18.024917ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T05:45:13.90128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.084753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T05:45:13.901716Z","caller":"traceutil/trace.go:171","msg":"trace[1317507279] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"206.998152ms","start":"2026-10-13T05:45:13.694696Z","end":"2026-10-13T05:45:13.901694Z","steps":["trace[1317507279] 'process raft request' (duration: 206.953082ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:45:13.901722Z","caller":"traceutil/trace.go:171","msg":"trace[28932911] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"273.729118ms","start":"2026-10-13T05:45:13.627957Z","end":"2026-10-13T05:45:13.901686Z","steps":["trace[28932911] 'process raft request' (duration: 273.431184ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:45:13.901781Z","caller":"traceutil/trace.go:171","msg":"trace[1022061886] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"271.304593ms","start":"2026-10-13T05:45:13.630452Z","end":"2026-10-13T05:45:13.901757Z","steps":["trace[1022061886] 'process raft request' (duration: 271.10443ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:45:13.901909Z","caller":"traceutil/trace.go:171","msg":"trace[39298518] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"273.544745ms","start":"2026-10-13T05:45:13.628338Z","end":"2026-10-13T05:45:13.901883Z","steps":["trace[39298518] 'process raft request' (duration: 273.140769ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:45:13.901957Z","caller":"traceutil/trace.go:171","msg":"trace[534574676] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"274.161363ms","start":"2026-10-13T05:45:13.62776Z","end":"2026-10-13T05:45:13.901921Z","steps":["trace[534574676] 'process raft request' (duration: 122.31816ms)","trace[534574676] 'compare' (duration: 150.697797ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T05:45:13.902053Z","caller":"traceutil/trace.go:171","msg":"trace[1850589835] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"226.176587ms","start":"2026-10-13T05:45:13.675865Z","end":"2026-10-13T05:45:13.902042Z","steps":["trace[1850589835] 'process raft request' (duration: 225.734761ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T05:46:02.844716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2856} {"level":"info","ts":"2026-10-13T05:46:02.97278Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2856,"took":"126.718172ms","hash":1754986209,"current-db-size-bytes":52604928,"current-db-size":"53 MB","current-db-size-in-use-bytes":28581888,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-13T05:46:02.972876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1754986209,"revision":2856,"compact-revision":-1} {"level":"info","ts":"2026-10-13T05:51:02.861568Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5443} {"level":"info","ts":"2026-10-13T05:51:02.984382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5443,"took":"121.317835ms","hash":4093868911,"current-db-size-bytes":52604928,"current-db-size":"53 MB","current-db-size-in-use-bytes":18612224,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-13T05:51:02.984445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4093868911,"revision":5443,"compact-revision":2856}