{"level":"warn","ts":"2026-09-04T00:16:25.129719Z","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-04T00:16:25.129872Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.5: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.5:2380","--initial-cluster=instance=https://162.253.55.5:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.5:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.5: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-04T00:16:25.130011Z","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-04T00:16:25.130033Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-09-04T00:16:25.130066Z","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-04T00:16:25.131243Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"]} {"level":"info","ts":"2026-09-04T00:16:25.131377Z","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.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.5: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-04T00:16:25.139112Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.368729ms"} {"level":"info","ts":"2026-09-04T00:16:25.154829Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b512f7d8ac93be25","cluster-id":"e1953d9a3e7368e5"} {"level":"info","ts":"2026-09-04T00:16:25.154899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=()"} {"level":"info","ts":"2026-09-04T00:16:25.154921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 0"} {"level":"info","ts":"2026-09-04T00:16:25.154935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b512f7d8ac93be25 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-04T00:16:25.154941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 1"} {"level":"info","ts":"2026-09-04T00:16:25.15497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"warn","ts":"2026-09-04T00:16:25.171061Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-04T00:16:25.172541Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-04T00:16:25.173952Z","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-04T00:16:25.176113Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b512f7d8ac93be25","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-04T00:16:25.176477Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b512f7d8ac93be25","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-04T00:16:25.176538Z","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-04T00:16:25.17658Z","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-04T00:16:25.176601Z","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-04T00:16:25.177156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"info","ts":"2026-09-04T00:16:25.180371Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","added-peer-id":"b512f7d8ac93be25","added-peer-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-09-04T00:16:25.183828Z","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-04T00:16:25.183877Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-09-04T00:16:25.183888Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-09-04T00:16:25.184839Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b512f7d8ac93be25","initial-advertise-peer-urls":["https://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-04T00:16:25.184867Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-04T00:16:25.555605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 is starting a new election at term 1"} {"level":"info","ts":"2026-09-04T00:16:25.555725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-04T00:16:25.555766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgPreVoteResp from b512f7d8ac93be25 at term 1"} {"level":"info","ts":"2026-09-04T00:16:25.555793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became candidate at term 2"} {"level":"info","ts":"2026-09-04T00:16:25.555809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgVoteResp from b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-09-04T00:16:25.555829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became leader at term 2"} {"level":"info","ts":"2026-09-04T00:16:25.55585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b512f7d8ac93be25 elected leader b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-09-04T00:16:25.557173Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:16:25.557561Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b512f7d8ac93be25","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.5:2379]}","request-path":"/0/members/b512f7d8ac93be25/attributes","cluster-id":"e1953d9a3e7368e5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-04T00:16:25.557619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T00:16:25.557653Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T00:16:25.557904Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-04T00:16:25.557954Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-04T00:16:25.559311Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:16:25.560873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.5:2379"} {"level":"info","ts":"2026-09-04T00:16:25.561Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:16:25.561103Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T00:16:25.562727Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-04T00:16:44.638523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.611771ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-04T00:17:13.770738Z","caller":"traceutil/trace.go:171","msg":"trace[1888502384] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"114.650826ms","start":"2026-09-04T00:17:13.656063Z","end":"2026-09-04T00:17:13.770714Z","steps":["trace[1888502384] 'process raft request' (duration: 114.297395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:17:14.417999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.163354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:17:14.418194Z","caller":"traceutil/trace.go:171","msg":"trace[1364291043] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"522.029039ms","start":"2026-09-04T00:17:13.896144Z","end":"2026-09-04T00:17:14.418173Z","steps":["trace[1364291043] 'process raft request' (duration: 67.619338ms)","trace[1364291043] 'compare' (duration: 452.986528ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:17:14.418286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:17:13.896125Z","time spent":"522.117821ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:17:14.418304Z","caller":"traceutil/trace.go:171","msg":"trace[1861482402] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"513.438481ms","start":"2026-09-04T00:17:13.90482Z","end":"2026-09-04T00:17:14.418259Z","steps":["trace[1861482402] 'read index received' (duration: 58.810003ms)","trace[1861482402] 'applied index is now lower than readState.Index' (duration: 454.623618ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:17:14.438164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.834227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T00:17:14.438189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.357153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:17:14.438234Z","caller":"traceutil/trace.go:171","msg":"trace[1428796925] range","detail":"{range_begin:/registry/resourcequotas/envoy-gateway-system/; range_end:/registry/resourcequotas/envoy-gateway-system0; response_count:0; response_revision:591; }","duration":"532.918079ms","start":"2026-09-04T00:17:13.905297Z","end":"2026-09-04T00:17:14.438215Z","steps":["trace[1428796925] 'agreement among raft nodes before linearized reading' (duration: 532.819936ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:17:14.438246Z","caller":"traceutil/trace.go:171","msg":"trace[495509537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"533.432325ms","start":"2026-09-04T00:17:13.904792Z","end":"2026-09-04T00:17:14.438225Z","steps":["trace[495509537] 'agreement among raft nodes before linearized reading' (duration: 513.571665ms)","trace[495509537] 'range keys from in-memory index tree' (duration: 19.773238ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:17:14.438279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:17:13.905292Z","time spent":"532.974331ms","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-09-04T00:17:14.438285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:17:13.904782Z","time spent":"533.493867ms","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-04T00:17:16.071063Z","caller":"traceutil/trace.go:171","msg":"trace[653373896] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"125.662152ms","start":"2026-09-04T00:17:15.945328Z","end":"2026-09-04T00:17:16.07099Z","steps":["trace[653373896] 'process raft request' (duration: 117.923465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:17:30.736505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.35229ms","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-09-04T00:17:30.736631Z","caller":"traceutil/trace.go:171","msg":"trace[1428689358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"238.494374ms","start":"2026-09-04T00:17:30.498114Z","end":"2026-09-04T00:17:30.736608Z","steps":["trace[1428689358] 'range keys from in-memory index tree' (duration: 238.160785ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:18:01.43562Z","caller":"traceutil/trace.go:171","msg":"trace[1878283880] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"104.719022ms","start":"2026-09-04T00:18:01.330867Z","end":"2026-09-04T00:18:01.435586Z","steps":["trace[1878283880] 'process raft request' (duration: 104.510486ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:18:01.448779Z","caller":"traceutil/trace.go:171","msg":"trace[1271518296] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"106.861038ms","start":"2026-09-04T00:18:01.341885Z","end":"2026-09-04T00:18:01.448746Z","steps":["trace[1271518296] 'process raft request' (duration: 106.646994ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:18:56.685678Z","caller":"traceutil/trace.go:171","msg":"trace[1216782888] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"346.94296ms","start":"2026-09-04T00:18:56.338708Z","end":"2026-09-04T00:18:56.685651Z","steps":["trace[1216782888] 'process raft request' (duration: 346.739667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:18:56.685907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:18:56.338684Z","time spent":"347.084403ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10968,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:18:56.695147Z","caller":"traceutil/trace.go:171","msg":"trace[1717051004] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"355.91413ms","start":"2026-09-04T00:18:56.339203Z","end":"2026-09-04T00:18:56.695117Z","steps":["trace[1717051004] 'process raft request' (duration: 355.728077ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:18:56.695291Z","caller":"traceutil/trace.go:171","msg":"trace[174049004] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1959; }","duration":"276.586014ms","start":"2026-09-04T00:18:56.41869Z","end":"2026-09-04T00:18:56.695276Z","steps":["trace[174049004] 'read index received' (duration: 266.666788ms)","trace[174049004] 'applied index is now lower than readState.Index' (duration: 9.918086ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:18:56.69568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.953241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a132865-2fec-4462-ba0c-ef223e685197\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-09-04T00:18:56.695769Z","caller":"traceutil/trace.go:171","msg":"trace[1319734565] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a132865-2fec-4462-ba0c-ef223e685197; range_end:; response_count:1; response_revision:1892; }","duration":"277.091942ms","start":"2026-09-04T00:18:56.418657Z","end":"2026-09-04T00:18:56.695749Z","steps":["trace[1319734565] 'agreement among raft nodes before linearized reading' (duration: 276.844228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:18:56.696633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:18:56.33919Z","time spent":"356.045612ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:18:56.852226Z","caller":"traceutil/trace.go:171","msg":"trace[1880271429] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"158.892037ms","start":"2026-09-04T00:18:56.693281Z","end":"2026-09-04T00:18:56.852173Z","steps":["trace[1880271429] 'process raft request' (duration: 103.870612ms)","trace[1880271429] 'compare' (duration: 54.76694ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:18:56.852255Z","caller":"traceutil/trace.go:171","msg":"trace[1889522628] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"158.47084ms","start":"2026-09-04T00:18:56.693754Z","end":"2026-09-04T00:18:56.852225Z","steps":["trace[1889522628] 'process raft request' (duration: 158.407759ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:18:56.852419Z","caller":"traceutil/trace.go:171","msg":"trace[1767872259] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"158.756964ms","start":"2026-09-04T00:18:56.693468Z","end":"2026-09-04T00:18:56.852225Z","steps":["trace[1767872259] 'process raft request' (duration: 158.609581ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:18:57.045857Z","caller":"traceutil/trace.go:171","msg":"trace[750291489] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1962; }","duration":"350.343389ms","start":"2026-09-04T00:18:56.695485Z","end":"2026-09-04T00:18:57.045829Z","steps":["trace[750291489] 'read index received' (duration: 101.732554ms)","trace[750291489] 'applied index is now lower than readState.Index' (duration: 248.591045ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:18:57.04612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.331557ms","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-04T00:18:57.045905Z","caller":"traceutil/trace.go:171","msg":"trace[2131285572] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"350.836378ms","start":"2026-09-04T00:18:56.695032Z","end":"2026-09-04T00:18:57.045868Z","steps":["trace[2131285572] 'process raft request' (duration: 350.607074ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:18:57.046179Z","caller":"traceutil/trace.go:171","msg":"trace[1263146901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1896; }","duration":"430.457821ms","start":"2026-09-04T00:18:56.615711Z","end":"2026-09-04T00:18:57.046169Z","steps":["trace[1263146901] 'agreement among raft nodes before linearized reading' (duration: 430.291447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:18:57.046213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:18:56.615701Z","time spent":"430.503221ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-04T00:18:57.046326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.616144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T00:18:57.046391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.002071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-04T00:18:57.046433Z","caller":"traceutil/trace.go:171","msg":"trace[1013069595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1896; }","duration":"430.067423ms","start":"2026-09-04T00:18:56.616356Z","end":"2026-09-04T00:18:57.046423Z","steps":["trace[1013069595] 'agreement among raft nodes before linearized reading' (duration: 429.572184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:18:57.046467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:18:56.616345Z","time spent":"430.113874ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-04T00:18:57.046333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:18:56.694986Z","time spent":"351.269606ms","remote":"127.0.0.1:49540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:18:57.046401Z","caller":"traceutil/trace.go:171","msg":"trace[171700882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"139.839558ms","start":"2026-09-04T00:18:56.906548Z","end":"2026-09-04T00:18:57.046388Z","steps":["trace[171700882] 'agreement among raft nodes before linearized reading' (duration: 139.543852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:18:57.046946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.988684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a132865-2fec-4462-ba0c-ef223e685197\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-09-04T00:18:57.046989Z","caller":"traceutil/trace.go:171","msg":"trace[447867817] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0a132865-2fec-4462-ba0c-ef223e685197; range_end:; response_count:1; response_revision:1896; }","duration":"354.084985ms","start":"2026-09-04T00:18:56.692891Z","end":"2026-09-04T00:18:57.046976Z","steps":["trace[447867817] 'agreement among raft nodes before linearized reading' (duration: 352.992566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:18:57.047014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:18:56.692867Z","time spent":"354.139707ms","remote":"127.0.0.1:49458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0a132865-2fec-4462-ba0c-ef223e685197\" "} {"level":"info","ts":"2026-09-04T00:19:09.464874Z","caller":"traceutil/trace.go:171","msg":"trace[1170938049] linearizableReadLoop","detail":"{readStateIndex:2085; appliedIndex:2084; }","duration":"136.583134ms","start":"2026-09-04T00:19:09.328271Z","end":"2026-09-04T00:19:09.464854Z","steps":["trace[1170938049] 'read index received' (duration: 136.388481ms)","trace[1170938049] 'applied index is now lower than readState.Index' (duration: 194.093µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T00:19:09.465017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.721956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:19:09.465037Z","caller":"traceutil/trace.go:171","msg":"trace[993273469] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2011; }","duration":"136.811508ms","start":"2026-09-04T00:19:09.32822Z","end":"2026-09-04T00:19:09.465032Z","steps":["trace[993273469] 'agreement among raft nodes before linearized reading' (duration: 136.710257ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:19:09.465363Z","caller":"traceutil/trace.go:171","msg":"trace[1047522976] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"187.571081ms","start":"2026-09-04T00:19:09.277785Z","end":"2026-09-04T00:19:09.465356Z","steps":["trace[1047522976] 'process raft request' (duration: 186.948369ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:19:31.031805Z","caller":"traceutil/trace.go:171","msg":"trace[577478427] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2211; }","duration":"124.260177ms","start":"2026-09-04T00:19:30.907512Z","end":"2026-09-04T00:19:31.031772Z","steps":["trace[577478427] 'read index received' (duration: 124.059474ms)","trace[577478427] 'applied index is now lower than readState.Index' (duration: 199.083µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:19:31.031889Z","caller":"traceutil/trace.go:171","msg":"trace[994349847] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"153.632071ms","start":"2026-09-04T00:19:30.878224Z","end":"2026-09-04T00:19:31.031856Z","steps":["trace[994349847] 'process raft request' (duration: 153.348887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:19:31.032139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.604302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:19:31.032285Z","caller":"traceutil/trace.go:171","msg":"trace[1276119134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2134; }","duration":"124.818776ms","start":"2026-09-04T00:19:30.90744Z","end":"2026-09-04T00:19:31.032259Z","steps":["trace[1276119134] 'agreement among raft nodes before linearized reading' (duration: 124.548512ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:19:31.772812Z","caller":"traceutil/trace.go:171","msg":"trace[212359280] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"173.000817ms","start":"2026-09-04T00:19:31.599774Z","end":"2026-09-04T00:19:31.772775Z","steps":["trace[212359280] 'process raft request' (duration: 172.767613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:19:32.046509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.813598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T00:19:32.046706Z","caller":"traceutil/trace.go:171","msg":"trace[1287327069] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"217.241763ms","start":"2026-09-04T00:19:31.829442Z","end":"2026-09-04T00:19:32.046684Z","steps":["trace[1287327069] 'process raft request' (duration: 111.136159ms)","trace[1287327069] 'compare' (duration: 105.704387ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:19:32.047233Z","caller":"traceutil/trace.go:171","msg":"trace[174667652] linearizableReadLoop","detail":"{readStateIndex:2217; appliedIndex:2215; }","duration":"147.317141ms","start":"2026-09-04T00:19:31.899879Z","end":"2026-09-04T00:19:32.047196Z","steps":["trace[174667652] 'read index received' (duration: 40.368414ms)","trace[174667652] 'applied index is now lower than readState.Index' (duration: 106.946937ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:19:32.047418Z","caller":"traceutil/trace.go:171","msg":"trace[2030125376] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"186.355204ms","start":"2026-09-04T00:19:31.860963Z","end":"2026-09-04T00:19:32.047318Z","steps":["trace[2030125376] 'process raft request' (duration: 186.016089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:19:32.048677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.741075ms","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-09-04T00:19:32.048762Z","caller":"traceutil/trace.go:171","msg":"trace[1108953129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2139; }","duration":"148.946898ms","start":"2026-09-04T00:19:31.899792Z","end":"2026-09-04T00:19:32.048739Z","steps":["trace[1108953129] 'agreement among raft nodes before linearized reading' (duration: 147.540885ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:19:32.26095Z","caller":"traceutil/trace.go:171","msg":"trace[1077602552] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"216.472546ms","start":"2026-09-04T00:19:32.044448Z","end":"2026-09-04T00:19:32.260921Z","steps":["trace[1077602552] 'process raft request' (duration: 216.250181ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:19:32.262169Z","caller":"traceutil/trace.go:171","msg":"trace[446583930] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"208.121577ms","start":"2026-09-04T00:19:32.054032Z","end":"2026-09-04T00:19:32.262154Z","steps":["trace[446583930] 'process raft request' (duration: 207.730451ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:21:28.721689Z","caller":"traceutil/trace.go:171","msg":"trace[2070459394] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"198.5108ms","start":"2026-09-04T00:21:28.523167Z","end":"2026-09-04T00:21:28.721678Z","steps":["trace[2070459394] 'process raft request' (duration: 198.381516ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:21:28.721605Z","caller":"traceutil/trace.go:171","msg":"trace[1504959895] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"207.943708ms","start":"2026-09-04T00:21:28.513631Z","end":"2026-09-04T00:21:28.721575Z","steps":["trace[1504959895] 'process raft request' (duration: 126.673631ms)","trace[1504959895] 'compare' (duration: 81.125083ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:25:52.758031Z","caller":"traceutil/trace.go:171","msg":"trace[1659851412] linearizableReadLoop","detail":"{readStateIndex:5020; appliedIndex:5019; }","duration":"316.067634ms","start":"2026-09-04T00:25:52.44194Z","end":"2026-09-04T00:25:52.758007Z","steps":["trace[1659851412] 'read index received' (duration: 315.799739ms)","trace[1659851412] 'applied index is now lower than readState.Index' (duration: 266.755µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:25:52.758111Z","caller":"traceutil/trace.go:171","msg":"trace[1108115317] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"329.029326ms","start":"2026-09-04T00:25:52.42904Z","end":"2026-09-04T00:25:52.75807Z","steps":["trace[1108115317] 'process raft request' (duration: 328.798022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:25:52.758259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.293138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-04T00:25:52.758306Z","caller":"traceutil/trace.go:171","msg":"trace[2034247133] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4837; }","duration":"316.381719ms","start":"2026-09-04T00:25:52.44191Z","end":"2026-09-04T00:25:52.758291Z","steps":["trace[2034247133] 'agreement among raft nodes before linearized reading' (duration: 316.200856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:25:52.758355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:25:52.441901Z","time spent":"316.44504ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-09-04T00:25:52.758616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T00:25:52.429031Z","time spent":"329.158369ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T00:26:05.979874Z","caller":"traceutil/trace.go:171","msg":"trace[1097676901] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"139.706799ms","start":"2026-09-04T00:26:05.84014Z","end":"2026-09-04T00:26:05.979846Z","steps":["trace[1097676901] 'process raft request' (duration: 123.645251ms)","trace[1097676901] 'compare' (duration: 15.957216ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:06.008163Z","caller":"traceutil/trace.go:171","msg":"trace[2110440955] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"164.778916ms","start":"2026-09-04T00:26:05.843361Z","end":"2026-09-04T00:26:06.00814Z","steps":["trace[2110440955] 'process raft request' (duration: 164.549181ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:06.008476Z","caller":"traceutil/trace.go:171","msg":"trace[1802164071] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5275; }","duration":"102.61416ms","start":"2026-09-04T00:26:05.905841Z","end":"2026-09-04T00:26:06.008456Z","steps":["trace[1802164071] 'read index received' (duration: 57.956139ms)","trace[1802164071] 'applied index is now lower than readState.Index' (duration: 44.656721ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:06.008679Z","caller":"traceutil/trace.go:171","msg":"trace[1323761601] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"160.231222ms","start":"2026-09-04T00:26:05.848432Z","end":"2026-09-04T00:26:06.008664Z","steps":["trace[1323761601] 'process raft request' (duration: 159.64251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:06.008936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.069208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T00:26:06.009049Z","caller":"traceutil/trace.go:171","msg":"trace[164865382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5087; }","duration":"103.213451ms","start":"2026-09-04T00:26:05.905786Z","end":"2026-09-04T00:26:06.008999Z","steps":["trace[164865382] 'agreement among raft nodes before linearized reading' (duration: 103.017577ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:20.681764Z","caller":"traceutil/trace.go:171","msg":"trace[1756109172] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"256.482337ms","start":"2026-09-04T00:26:20.425233Z","end":"2026-09-04T00:26:20.681715Z","steps":["trace[1756109172] 'process raft request' (duration: 190.991427ms)","trace[1756109172] 'compare' (duration: 65.237424ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T00:26:20.891554Z","caller":"traceutil/trace.go:171","msg":"trace[1387042586] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"149.335798ms","start":"2026-09-04T00:26:20.742194Z","end":"2026-09-04T00:26:20.89153Z","steps":["trace[1387042586] 'process raft request' (duration: 149.085823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T00:26:20.892251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.301282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-04T00:26:20.892344Z","caller":"traceutil/trace.go:171","msg":"trace[1527546023] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5279; }","duration":"100.425785ms","start":"2026-09-04T00:26:20.79188Z","end":"2026-09-04T00:26:20.892306Z","steps":["trace[1527546023] 'agreement among raft nodes before linearized reading' (duration: 100.211781ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T00:26:25.74008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2852} {"level":"info","ts":"2026-09-04T00:26:25.820064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2852,"took":"78.428585ms","hash":925144169,"current-db-size-bytes":52101120,"current-db-size":"52 MB","current-db-size-in-use-bytes":27480064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T00:26:25.820164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":925144169,"revision":2852,"compact-revision":-1} {"level":"info","ts":"2026-09-04T00:31:25.747113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5309} {"level":"info","ts":"2026-09-04T00:31:25.860228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5309,"took":"111.761733ms","hash":4139623055,"current-db-size-bytes":52101120,"current-db-size":"52 MB","current-db-size-in-use-bytes":18767872,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-04T00:31:25.860285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4139623055,"revision":5309,"compact-revision":2852} {"level":"info","ts":"2026-09-04T00:34:04.952148Z","caller":"traceutil/trace.go:171","msg":"trace[72277897] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"111.700838ms","start":"2026-09-04T00:34:04.840418Z","end":"2026-09-04T00:34:04.952119Z","steps":["trace[72277897] 'process raft request' (duration: 111.346768ms)"],"step_count":1}