{"level":"warn","ts":"2026-09-03T16:16:28.85968Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-03T16:16:28.859834Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.55: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.55:2380","--initial-cluster=instance=https://162.253.55.55:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.55:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.55: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-09-03T16:16:28.859994Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-03T16:16:28.860042Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-09-03T16:16:28.860071Z","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-09-03T16:16:28.861718Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"]} {"level":"info","ts":"2026-09-03T16:16:28.86186Z","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.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.55: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-09-03T16:16:28.868871Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.682819ms"} {"level":"info","ts":"2026-09-03T16:16:28.881605Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"357bf0025115def9","cluster-id":"5f69314e1ae3c800"} {"level":"info","ts":"2026-09-03T16:16:28.881886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-03T16:16:28.881917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 0"} {"level":"info","ts":"2026-09-03T16:16:28.88198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 357bf0025115def9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-03T16:16:28.882064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became follower at term 1"} {"level":"info","ts":"2026-09-03T16:16:28.882197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"warn","ts":"2026-09-03T16:16:28.895218Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-03T16:16:28.897706Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-03T16:16:28.899053Z","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-09-03T16:16:28.90286Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"357bf0025115def9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-03T16:16:28.903496Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"357bf0025115def9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-03T16:16:28.903643Z","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-09-03T16:16:28.90369Z","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-09-03T16:16:28.903697Z","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-09-03T16:16:28.908629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 switched to configuration voters=(3853937798886579961)"} {"level":"info","ts":"2026-09-03T16:16:28.908833Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","added-peer-id":"357bf0025115def9","added-peer-peer-urls":["https://162.253.55.55:2380"]} {"level":"info","ts":"2026-09-03T16:16:28.912647Z","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-09-03T16:16:28.912855Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-09-03T16:16:28.912911Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.55:2380"} {"level":"info","ts":"2026-09-03T16:16:28.912936Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"357bf0025115def9","initial-advertise-peer-urls":["https://162.253.55.55:2380"],"listen-peer-urls":["https://162.253.55.55:2380"],"advertise-client-urls":["https://162.253.55.55:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.55:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-03T16:16:28.912972Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-03T16:16:29.584595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-03T16:16:29.584648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-03T16:16:29.584674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgPreVoteResp from 357bf0025115def9 at term 1"} {"level":"info","ts":"2026-09-03T16:16:29.584684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became candidate at term 2"} {"level":"info","ts":"2026-09-03T16:16:29.584689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 received MsgVoteResp from 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-09-03T16:16:29.584696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"357bf0025115def9 became leader at term 2"} {"level":"info","ts":"2026-09-03T16:16:29.584702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 357bf0025115def9 elected leader 357bf0025115def9 at term 2"} {"level":"info","ts":"2026-09-03T16:16:29.586824Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"357bf0025115def9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.55:2379]}","request-path":"/0/members/357bf0025115def9/attributes","cluster-id":"5f69314e1ae3c800","publish-timeout":"7s"} {"level":"info","ts":"2026-09-03T16:16:29.586998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-03T16:16:29.58698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-03T16:16:29.587133Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T16:16:29.587432Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-03T16:16:29.587457Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-03T16:16:29.588395Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f69314e1ae3c800","local-member-id":"357bf0025115def9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T16:16:29.588512Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T16:16:29.588543Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T16:16:29.588745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-03T16:16:29.590432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.55:2379"} {"level":"info","ts":"2026-09-03T16:17:02.395073Z","caller":"traceutil/trace.go:171","msg":"trace[194283008] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"103.426418ms","start":"2026-09-03T16:17:02.291623Z","end":"2026-09-03T16:17:02.395049Z","steps":["trace[194283008] 'process raft request' (duration: 103.258694ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:17:05.401908Z","caller":"traceutil/trace.go:171","msg":"trace[1935530966] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"118.222428ms","start":"2026-09-03T16:17:05.283663Z","end":"2026-09-03T16:17:05.401885Z","steps":["trace[1935530966] 'process raft request' (duration: 111.03657ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:17:05.405279Z","caller":"traceutil/trace.go:171","msg":"trace[493791703] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"121.060895ms","start":"2026-09-03T16:17:05.284182Z","end":"2026-09-03T16:17:05.405243Z","steps":["trace[493791703] 'process raft request' (duration: 118.358941ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:17:05.407918Z","caller":"traceutil/trace.go:171","msg":"trace[554426716] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"116.141204ms","start":"2026-09-03T16:17:05.291747Z","end":"2026-09-03T16:17:05.407888Z","steps":["trace[554426716] 'process raft request' (duration: 111.769723ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:17:11.484592Z","caller":"traceutil/trace.go:171","msg":"trace[722603604] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"130.776921ms","start":"2026-09-03T16:17:11.353744Z","end":"2026-09-03T16:17:11.484521Z","steps":["trace[722603604] 'process raft request' (duration: 130.059817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:17:12.093331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.232942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:17:12.093483Z","caller":"traceutil/trace.go:171","msg":"trace[554038414] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"515.522417ms","start":"2026-09-03T16:17:11.577932Z","end":"2026-09-03T16:17:12.093454Z","steps":["trace[554038414] 'process raft request' (duration: 397.504238ms)","trace[554038414] 'compare' (duration: 117.022281ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:17:12.093564Z","caller":"traceutil/trace.go:171","msg":"trace[2021122955] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"383.403158ms","start":"2026-09-03T16:17:11.710126Z","end":"2026-09-03T16:17:12.093529Z","steps":["trace[2021122955] 'read index received' (duration: 265.332587ms)","trace[2021122955] 'applied index is now lower than readState.Index' (duration: 118.067491ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:17:12.093702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:17:11.577909Z","time spent":"515.631422ms","remote":"127.0.0.1:55522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-03T16:17:12.093763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.963674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:17:12.093791Z","caller":"traceutil/trace.go:171","msg":"trace[381342963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"342.023797ms","start":"2026-09-03T16:17:11.751759Z","end":"2026-09-03T16:17:12.093783Z","steps":["trace[381342963] 'agreement among raft nodes before linearized reading' (duration: 341.933803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:17:12.093829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:17:11.75175Z","time spent":"342.057578ms","remote":"127.0.0.1:36806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-03T16:17:12.093833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.692713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tpb6q\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-03T16:17:12.09391Z","caller":"traceutil/trace.go:171","msg":"trace[1212936833] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tpb6q; range_end:; response_count:1; response_revision:590; }","duration":"383.801338ms","start":"2026-09-03T16:17:11.710092Z","end":"2026-09-03T16:17:12.093894Z","steps":["trace[1212936833] 'agreement among raft nodes before linearized reading' (duration: 383.546545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:17:12.093947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:17:11.710075Z","time spent":"383.862982ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-tpb6q\" "} {"level":"info","ts":"2026-09-03T16:18:55.942519Z","caller":"traceutil/trace.go:171","msg":"trace[435205948] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"142.67253ms","start":"2026-09-03T16:18:55.799825Z","end":"2026-09-03T16:18:55.942498Z","steps":["trace[435205948] 'read index received' (duration: 142.456774ms)","trace[435205948] 'applied index is now lower than readState.Index' (duration: 214.926µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:18:55.942586Z","caller":"traceutil/trace.go:171","msg":"trace[1242334246] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"150.988521ms","start":"2026-09-03T16:18:55.791563Z","end":"2026-09-03T16:18:55.942552Z","steps":["trace[1242334246] 'process raft request' (duration: 150.798636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:18:55.942634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.800563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:18:55.942664Z","caller":"traceutil/trace.go:171","msg":"trace[2057403679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"142.885145ms","start":"2026-09-03T16:18:55.79977Z","end":"2026-09-03T16:18:55.942655Z","steps":["trace[2057403679] 'agreement among raft nodes before linearized reading' (duration: 142.819434ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:18:56.310845Z","caller":"traceutil/trace.go:171","msg":"trace[1901455555] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"270.285102ms","start":"2026-09-03T16:18:56.040541Z","end":"2026-09-03T16:18:56.310826Z","steps":["trace[1901455555] 'read index received' (duration: 270.19845ms)","trace[1901455555] 'applied index is now lower than readState.Index' (duration: 85.752µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:18:56.310952Z","caller":"traceutil/trace.go:171","msg":"trace[1825848034] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"279.321698ms","start":"2026-09-03T16:18:56.031592Z","end":"2026-09-03T16:18:56.310914Z","steps":["trace[1825848034] 'process raft request' (duration: 279.084883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:18:56.311053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.497277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fbf1ee99-20b6-43f6-8688-4cf5714c7b0b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-03T16:18:56.311083Z","caller":"traceutil/trace.go:171","msg":"trace[831766038] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fbf1ee99-20b6-43f6-8688-4cf5714c7b0b; range_end:; response_count:1; response_revision:1865; }","duration":"270.560148ms","start":"2026-09-03T16:18:56.040516Z","end":"2026-09-03T16:18:56.311076Z","steps":["trace[831766038] 'agreement among raft nodes before linearized reading' (duration: 270.411034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:18:56.347483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.070589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-03T16:18:56.347586Z","caller":"traceutil/trace.go:171","msg":"trace[1715118038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1866; }","duration":"298.194893ms","start":"2026-09-03T16:18:56.049382Z","end":"2026-09-03T16:18:56.347577Z","steps":["trace[1715118038] 'agreement among raft nodes before linearized reading' (duration: 298.003198ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:18:56.347463Z","caller":"traceutil/trace.go:171","msg":"trace[1920078161] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"136.865106ms","start":"2026-09-03T16:18:56.210569Z","end":"2026-09-03T16:18:56.347434Z","steps":["trace[1920078161] 'process raft request' (duration: 128.135817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:18:56.348381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.186795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fbf1ee99-20b6-43f6-8688-4cf5714c7b0b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-03T16:18:56.348439Z","caller":"traceutil/trace.go:171","msg":"trace[243080971] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fbf1ee99-20b6-43f6-8688-4cf5714c7b0b; range_end:; response_count:1; response_revision:1866; }","duration":"137.304938ms","start":"2026-09-03T16:18:56.211118Z","end":"2026-09-03T16:18:56.348423Z","steps":["trace[243080971] 'agreement among raft nodes before linearized reading' (duration: 136.494968ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:19:11.729779Z","caller":"traceutil/trace.go:171","msg":"trace[554962320] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"114.445978ms","start":"2026-09-03T16:19:11.615241Z","end":"2026-09-03T16:19:11.729687Z","steps":["trace[554962320] 'process raft request' (duration: 109.618088ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:19:13.337869Z","caller":"traceutil/trace.go:171","msg":"trace[1060926914] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"242.036572ms","start":"2026-09-03T16:19:13.09581Z","end":"2026-09-03T16:19:13.337847Z","steps":["trace[1060926914] 'process raft request' (duration: 241.895509ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:19:29.012929Z","caller":"traceutil/trace.go:171","msg":"trace[933067637] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"114.337883ms","start":"2026-09-03T16:19:28.898574Z","end":"2026-09-03T16:19:29.012912Z","steps":["trace[933067637] 'process raft request' (duration: 114.203769ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:19:29.796285Z","caller":"traceutil/trace.go:171","msg":"trace[1040088056] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"101.11357ms","start":"2026-09-03T16:19:29.695147Z","end":"2026-09-03T16:19:29.796261Z","steps":["trace[1040088056] 'process raft request' (duration: 100.950146ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:19:29.813764Z","caller":"traceutil/trace.go:171","msg":"trace[367641907] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"104.625495ms","start":"2026-09-03T16:19:29.709122Z","end":"2026-09-03T16:19:29.813748Z","steps":["trace[367641907] 'process raft request' (duration: 104.351799ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:19:29.813866Z","caller":"traceutil/trace.go:171","msg":"trace[638251] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"104.479023ms","start":"2026-09-03T16:19:29.70938Z","end":"2026-09-03T16:19:29.813859Z","steps":["trace[638251] 'process raft request' (duration: 104.202337ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:19:30.766519Z","caller":"traceutil/trace.go:171","msg":"trace[502311329] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"209.041536ms","start":"2026-09-03T16:19:30.557459Z","end":"2026-09-03T16:19:30.7665Z","steps":["trace[502311329] 'read index received' (duration: 208.881122ms)","trace[502311329] 'applied index is now lower than readState.Index' (duration: 157.804µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:19:30.766584Z","caller":"traceutil/trace.go:171","msg":"trace[1181037640] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"227.74514ms","start":"2026-09-03T16:19:30.538809Z","end":"2026-09-03T16:19:30.766554Z","steps":["trace[1181037640] 'process raft request' (duration: 227.571926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:19:30.76673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.25478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-03T16:19:30.766768Z","caller":"traceutil/trace.go:171","msg":"trace[222059639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2112; }","duration":"209.333112ms","start":"2026-09-03T16:19:30.557426Z","end":"2026-09-03T16:19:30.766759Z","steps":["trace[222059639] 'agreement among raft nodes before linearized reading' (duration: 209.200969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:19:30.766997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.094772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-03T16:19:30.767044Z","caller":"traceutil/trace.go:171","msg":"trace[2092185413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2112; }","duration":"206.155064ms","start":"2026-09-03T16:19:30.560876Z","end":"2026-09-03T16:19:30.767031Z","steps":["trace[2092185413] 'agreement among raft nodes before linearized reading' (duration: 205.819567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:19:31.082268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.25362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:19:31.082393Z","caller":"traceutil/trace.go:171","msg":"trace[376802191] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"312.502557ms","start":"2026-09-03T16:19:30.769878Z","end":"2026-09-03T16:19:31.082381Z","steps":["trace[376802191] 'process raft request' (duration: 87.959861ms)","trace[376802191] 'compare' (duration: 224.169628ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:19:31.082435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:19:30.769861Z","time spent":"312.555318ms","remote":"127.0.0.1:37040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T16:19:31.082538Z","caller":"traceutil/trace.go:171","msg":"trace[238366600] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"312.082637ms","start":"2026-09-03T16:19:30.770431Z","end":"2026-09-03T16:19:31.082514Z","steps":["trace[238366600] 'process raft request' (duration: 311.916034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:19:31.082689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:19:30.770415Z","time spent":"312.17586ms","remote":"127.0.0.1:37040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T16:19:31.565026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.204926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:19:31.565098Z","caller":"traceutil/trace.go:171","msg":"trace[1144156826] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2195; }","duration":"477.264705ms","start":"2026-09-03T16:19:31.087822Z","end":"2026-09-03T16:19:31.565087Z","steps":["trace[1144156826] 'read index received' (duration: 120.969307ms)","trace[1144156826] 'applied index is now lower than readState.Index' (duration: 356.294258ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:19:31.565154Z","caller":"traceutil/trace.go:171","msg":"trace[330499759] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"477.471039ms","start":"2026-09-03T16:19:31.087676Z","end":"2026-09-03T16:19:31.565147Z","steps":["trace[330499759] 'process raft request' (duration: 121.07667ms)","trace[330499759] 'compare' (duration: 355.746795ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:19:31.565191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:19:31.087659Z","time spent":"477.51342ms","remote":"127.0.0.1:37040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T16:19:31.565501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.674444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125893"} {"level":"info","ts":"2026-09-03T16:19:31.565521Z","caller":"traceutil/trace.go:171","msg":"trace[153360576] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2115; }","duration":"477.717406ms","start":"2026-09-03T16:19:31.087799Z","end":"2026-09-03T16:19:31.565516Z","steps":["trace[153360576] 'agreement among raft nodes before linearized reading' (duration: 477.601593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:19:31.565535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:19:31.087789Z","time spent":"477.742986ms","remote":"127.0.0.1:36842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":125915,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-09-03T16:20:51.424263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.036728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:20:51.424493Z","caller":"traceutil/trace.go:171","msg":"trace[1636625257] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2641; }","duration":"201.368705ms","start":"2026-09-03T16:20:51.2231Z","end":"2026-09-03T16:20:51.424469Z","steps":["trace[1636625257] 'range keys from in-memory index tree' (duration: 200.898725ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:21:22.2203Z","caller":"traceutil/trace.go:171","msg":"trace[1733675697] linearizableReadLoop","detail":"{readStateIndex:2917; appliedIndex:2916; }","duration":"101.783041ms","start":"2026-09-03T16:21:22.118471Z","end":"2026-09-03T16:21:22.220254Z","steps":["trace[1733675697] 'read index received' (duration: 101.629508ms)","trace[1733675697] 'applied index is now lower than readState.Index' (duration: 149.063µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:21:22.220628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.086687ms","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-09-03T16:21:22.220746Z","caller":"traceutil/trace.go:171","msg":"trace[222213957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2811; }","duration":"102.31787ms","start":"2026-09-03T16:21:22.118415Z","end":"2026-09-03T16:21:22.220733Z","steps":["trace[222213957] 'agreement among raft nodes before linearized reading' (duration: 101.963884ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:21:22.221211Z","caller":"traceutil/trace.go:171","msg":"trace[2026175944] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"135.283419ms","start":"2026-09-03T16:21:22.085041Z","end":"2026-09-03T16:21:22.220324Z","steps":["trace[2026175944] 'process raft request' (duration: 135.060595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:21:22.295784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.023992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:21:22.295883Z","caller":"traceutil/trace.go:171","msg":"trace[1661066896] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2813; }","duration":"162.181245ms","start":"2026-09-03T16:21:22.133681Z","end":"2026-09-03T16:21:22.295863Z","steps":["trace[1661066896] 'agreement among raft nodes before linearized reading' (duration: 162.013912ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:21:22.353183Z","caller":"traceutil/trace.go:171","msg":"trace[526718644] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"126.78386ms","start":"2026-09-03T16:21:22.225364Z","end":"2026-09-03T16:21:22.352147Z","steps":["trace[526718644] 'process raft request' (duration: 126.608277ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:21:27.775087Z","caller":"traceutil/trace.go:171","msg":"trace[586385487] linearizableReadLoop","detail":"{readStateIndex:2948; appliedIndex:2947; }","duration":"124.073737ms","start":"2026-09-03T16:21:27.650991Z","end":"2026-09-03T16:21:27.775064Z","steps":["trace[586385487] 'read index received' (duration: 123.9285ms)","trace[586385487] 'applied index is now lower than readState.Index' (duration: 144.167µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:21:27.775123Z","caller":"traceutil/trace.go:171","msg":"trace[903893398] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"132.486186ms","start":"2026-09-03T16:21:27.642603Z","end":"2026-09-03T16:21:27.775089Z","steps":["trace[903893398] 'process raft request' (duration: 132.317658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:21:27.775355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.349001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-03T16:21:27.775394Z","caller":"traceutil/trace.go:171","msg":"trace[1973727379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2841; }","duration":"124.426175ms","start":"2026-09-03T16:21:27.650956Z","end":"2026-09-03T16:21:27.775382Z","steps":["trace[1973727379] 'agreement among raft nodes before linearized reading' (duration: 124.229325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:21:27.775377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.580662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-09-03T16:21:27.775535Z","caller":"traceutil/trace.go:171","msg":"trace[531860605] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2841; }","duration":"108.76604ms","start":"2026-09-03T16:21:27.666755Z","end":"2026-09-03T16:21:27.775521Z","steps":["trace[531860605] 'agreement among raft nodes before linearized reading' (duration: 108.506548ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:25:54.274175Z","caller":"traceutil/trace.go:171","msg":"trace[1414845511] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5133; }","duration":"106.482921ms","start":"2026-09-03T16:25:54.167667Z","end":"2026-09-03T16:25:54.27415Z","steps":["trace[1414845511] 'read index received' (duration: 72.987313ms)","trace[1414845511] 'applied index is now lower than readState.Index' (duration: 33.494638ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:25:54.274256Z","caller":"traceutil/trace.go:171","msg":"trace[1180486104] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"112.9565ms","start":"2026-09-03T16:25:54.161272Z","end":"2026-09-03T16:25:54.274228Z","steps":["trace[1180486104] 'process raft request' (duration: 112.716054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:25:54.274509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.734847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-03T16:25:54.274663Z","caller":"traceutil/trace.go:171","msg":"trace[149180899] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4943; }","duration":"106.979473ms","start":"2026-09-03T16:25:54.167663Z","end":"2026-09-03T16:25:54.274642Z","steps":["trace[149180899] 'agreement among raft nodes before linearized reading' (duration: 106.593403ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:26:11.446381Z","caller":"traceutil/trace.go:171","msg":"trace[2066086595] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"108.278751ms","start":"2026-09-03T16:26:11.338058Z","end":"2026-09-03T16:26:11.446337Z","steps":["trace[2066086595] 'process raft request' (duration: 102.572072ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:26:11.446549Z","caller":"traceutil/trace.go:171","msg":"trace[1137633832] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"106.640281ms","start":"2026-09-03T16:26:11.339887Z","end":"2026-09-03T16:26:11.446527Z","steps":["trace[1137633832] 'process raft request' (duration: 106.538499ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:26:11.44657Z","caller":"traceutil/trace.go:171","msg":"trace[2143412898] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"108.059286ms","start":"2026-09-03T16:26:11.338501Z","end":"2026-09-03T16:26:11.44656Z","steps":["trace[2143412898] 'process raft request' (duration: 107.761909ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:26:29.699313Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2852} {"level":"info","ts":"2026-09-03T16:26:29.771538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2852,"took":"70.890857ms","hash":4239179954,"current-db-size-bytes":53047296,"current-db-size":"53 MB","current-db-size-in-use-bytes":27754496,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-03T16:26:29.771579Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4239179954,"revision":2852,"compact-revision":-1} {"level":"info","ts":"2026-09-03T16:30:22.925753Z","caller":"traceutil/trace.go:171","msg":"trace[1897882768] transaction","detail":"{read_only:false; response_revision:7021; number_of_response:1; }","duration":"113.537859ms","start":"2026-09-03T16:30:22.812187Z","end":"2026-09-03T16:30:22.925725Z","steps":["trace[1897882768] 'process raft request' (duration: 18.270284ms)","trace[1897882768] 'compare' (duration: 95.161721ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:31:29.705754Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5337} {"level":"info","ts":"2026-09-03T16:31:29.90689Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5337,"took":"198.673732ms","hash":2726017248,"current-db-size-bytes":53047296,"current-db-size":"53 MB","current-db-size-in-use-bytes":18747392,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-03T16:31:29.907023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2726017248,"revision":5337,"compact-revision":2852}