{"level":"warn","ts":"2026-03-20T10:13:56.780601Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T10:13:56.780692Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.221: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.221:2380","--initial-cluster=instance=https://162.253.55.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.221: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-03-20T10:13:56.780765Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T10:13:56.780778Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-20T10:13:56.780796Z","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-03-20T10:13:56.781424Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"]} {"level":"info","ts":"2026-03-20T10:13:56.781494Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.221: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-03-20T10:13:56.785633Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.902724ms"} {"level":"info","ts":"2026-03-20T10:13:56.793403Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c187bdee547e8fd1","cluster-id":"ee7528b898b8b18e"} {"level":"info","ts":"2026-03-20T10:13:56.793531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T10:13:56.793571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 0"} {"level":"info","ts":"2026-03-20T10:13:56.793596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c187bdee547e8fd1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T10:13:56.793621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 1"} {"level":"info","ts":"2026-03-20T10:13:56.793674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"warn","ts":"2026-03-20T10:13:56.801807Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T10:13:56.803424Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T10:13:56.804383Z","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-03-20T10:13:56.807034Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c187bdee547e8fd1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T10:13:56.807586Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c187bdee547e8fd1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T10:13:56.807751Z","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-03-20T10:13:56.807821Z","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-03-20T10:13:56.807835Z","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-03-20T10:13:56.810349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"info","ts":"2026-03-20T10:13:56.810832Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","added-peer-id":"c187bdee547e8fd1","added-peer-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-03-20T10:13:56.812972Z","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-03-20T10:13:56.813284Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c187bdee547e8fd1","initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T10:13:56.813324Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T10:13:56.813341Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-20T10:13:56.813425Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-03-20T10:13:57.294722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T10:13:57.294778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T10:13:57.294812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgPreVoteResp from c187bdee547e8fd1 at term 1"} {"level":"info","ts":"2026-03-20T10:13:57.294831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became candidate at term 2"} {"level":"info","ts":"2026-03-20T10:13:57.294842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgVoteResp from c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-20T10:13:57.294858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became leader at term 2"} {"level":"info","ts":"2026-03-20T10:13:57.294881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c187bdee547e8fd1 elected leader c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-03-20T10:13:57.295981Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:13:57.297009Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T10:13:57.297024Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c187bdee547e8fd1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.221:2379]}","request-path":"/0/members/c187bdee547e8fd1/attributes","cluster-id":"ee7528b898b8b18e","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T10:13:57.297343Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T10:13:57.297396Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:13:57.297519Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:13:57.297564Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:13:57.300213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.221:2379"} {"level":"info","ts":"2026-03-20T10:13:57.300924Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-20T10:13:57.311396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T10:13:57.311426Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T10:14:13.997743Z","caller":"traceutil/trace.go:171","msg":"trace[125987191] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"124.504457ms","start":"2026-03-20T10:14:13.873215Z","end":"2026-03-20T10:14:13.997719Z","steps":["trace[125987191] 'process raft request' (duration: 124.338384ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:14:38.824746Z","caller":"traceutil/trace.go:171","msg":"trace[872290088] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"227.172801ms","start":"2026-03-20T10:14:38.597553Z","end":"2026-03-20T10:14:38.824726Z","steps":["trace[872290088] 'read index received' (duration: 226.961248ms)","trace[872290088] 'applied index is now lower than readState.Index' (duration: 210.843µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:14:38.824892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.315095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:14:38.824929Z","caller":"traceutil/trace.go:171","msg":"trace[570392072] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:562; }","duration":"227.417526ms","start":"2026-03-20T10:14:38.597502Z","end":"2026-03-20T10:14:38.824919Z","steps":["trace[570392072] 'agreement among raft nodes before linearized reading' (duration: 227.314694ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:14:38.825609Z","caller":"traceutil/trace.go:171","msg":"trace[675674147] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"372.149275ms","start":"2026-03-20T10:14:38.452949Z","end":"2026-03-20T10:14:38.825098Z","steps":["trace[675674147] 'process raft request' (duration: 371.657066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:14:38.82771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:14:38.452928Z","time spent":"372.893379ms","remote":"127.0.0.1:38800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:14:39.806256Z","caller":"traceutil/trace.go:171","msg":"trace[459248402] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"100.774019ms","start":"2026-03-20T10:14:39.705466Z","end":"2026-03-20T10:14:39.80624Z","steps":["trace[459248402] 'process raft request' (duration: 95.677713ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:14:39.806246Z","caller":"traceutil/trace.go:171","msg":"trace[1542582064] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"100.108837ms","start":"2026-03-20T10:14:39.706114Z","end":"2026-03-20T10:14:39.806223Z","steps":["trace[1542582064] 'read index received' (duration: 95.00263ms)","trace[1542582064] 'applied index is now lower than readState.Index' (duration: 5.104377ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:14:39.806369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.23417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:14:39.806401Z","caller":"traceutil/trace.go:171","msg":"trace[237360534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"100.281331ms","start":"2026-03-20T10:14:39.70611Z","end":"2026-03-20T10:14:39.806391Z","steps":["trace[237360534] 'agreement among raft nodes before linearized reading' (duration: 100.179199ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:14:57.732239Z","caller":"traceutil/trace.go:171","msg":"trace[844200200] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"110.106232ms","start":"2026-03-20T10:14:57.622095Z","end":"2026-03-20T10:14:57.732201Z","steps":["trace[844200200] 'process raft request' (duration: 101.590523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:15:44.367603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.117304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a20dbebb-0f62-4e8d-a7a4-f68560cfa959\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-20T10:15:44.367697Z","caller":"traceutil/trace.go:171","msg":"trace[1924527580] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a20dbebb-0f62-4e8d-a7a4-f68560cfa959; range_end:; response_count:1; response_revision:1589; }","duration":"190.273616ms","start":"2026-03-20T10:15:44.177401Z","end":"2026-03-20T10:15:44.367675Z","steps":["trace[1924527580] 'range keys from in-memory index tree' (duration: 189.8896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:15:44.368163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.636886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:15:44.368344Z","caller":"traceutil/trace.go:171","msg":"trace[1257140555] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1589; }","duration":"143.914482ms","start":"2026-03-20T10:15:44.224393Z","end":"2026-03-20T10:15:44.368307Z","steps":["trace[1257140555] 'count revisions from in-memory index tree' (duration: 143.509034ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:15:44.832626Z","caller":"traceutil/trace.go:171","msg":"trace[1749072033] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"122.120546ms","start":"2026-03-20T10:15:44.710448Z","end":"2026-03-20T10:15:44.832569Z","steps":["trace[1749072033] 'process raft request' (duration: 121.939523ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:16:32.96688Z","caller":"traceutil/trace.go:171","msg":"trace[1123578973] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"161.847784ms","start":"2026-03-20T10:16:32.805002Z","end":"2026-03-20T10:16:32.96685Z","steps":["trace[1123578973] 'process raft request' (duration: 161.706441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:16:33.214532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.861961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-20T10:16:33.214646Z","caller":"traceutil/trace.go:171","msg":"trace[199617132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1877; }","duration":"140.030923ms","start":"2026-03-20T10:16:33.074594Z","end":"2026-03-20T10:16:33.214625Z","steps":["trace[199617132] 'range keys from in-memory index tree' (duration: 139.636166ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:21:28.655201Z","caller":"traceutil/trace.go:171","msg":"trace[1829360650] transaction","detail":"{read_only:false; response_revision:4243; number_of_response:1; }","duration":"112.072658ms","start":"2026-03-20T10:21:28.543108Z","end":"2026-03-20T10:21:28.65518Z","steps":["trace[1829360650] 'process raft request' (duration: 111.581639ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:21:35.651286Z","caller":"traceutil/trace.go:171","msg":"trace[407395609] linearizableReadLoop","detail":"{readStateIndex:4628; appliedIndex:4627; }","duration":"105.766901ms","start":"2026-03-20T10:21:35.545474Z","end":"2026-03-20T10:21:35.651241Z","steps":["trace[407395609] 'read index received' (duration: 105.420474ms)","trace[407395609] 'applied index is now lower than readState.Index' (duration: 345.377µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:21:35.651407Z","caller":"traceutil/trace.go:171","msg":"trace[701641976] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"223.121118ms","start":"2026-03-20T10:21:35.428254Z","end":"2026-03-20T10:21:35.651375Z","steps":["trace[701641976] 'process raft request' (duration: 222.750411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:21:35.651952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.467213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a1a849f-ff3e-4b35-9cb3-593614bafb4c\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-20T10:21:35.652011Z","caller":"traceutil/trace.go:171","msg":"trace[1651400028] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a1a849f-ff3e-4b35-9cb3-593614bafb4c; range_end:; response_count:1; response_revision:4451; }","duration":"106.581905ms","start":"2026-03-20T10:21:35.545412Z","end":"2026-03-20T10:21:35.651994Z","steps":["trace[1651400028] 'agreement among raft nodes before linearized reading' (duration: 106.008675ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:21:37.919789Z","caller":"traceutil/trace.go:171","msg":"trace[942472797] linearizableReadLoop","detail":"{readStateIndex:4670; appliedIndex:4667; }","duration":"118.606692ms","start":"2026-03-20T10:21:37.801164Z","end":"2026-03-20T10:21:37.919771Z","steps":["trace[942472797] 'read index received' (duration: 44.917375ms)","trace[942472797] 'applied index is now lower than readState.Index' (duration: 73.687777ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:21:37.919868Z","caller":"traceutil/trace.go:171","msg":"trace[919611996] transaction","detail":"{read_only:false; response_revision:4490; number_of_response:1; }","duration":"148.850521ms","start":"2026-03-20T10:21:37.77097Z","end":"2026-03-20T10:21:37.919821Z","steps":["trace[919611996] 'process raft request' (duration: 148.671008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:21:37.919964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.786985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-20T10:21:37.920002Z","caller":"traceutil/trace.go:171","msg":"trace[1258446641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4491; }","duration":"118.864387ms","start":"2026-03-20T10:21:37.801119Z","end":"2026-03-20T10:21:37.919983Z","steps":["trace[1258446641] 'agreement among raft nodes before linearized reading' (duration: 118.727814ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:21:37.919994Z","caller":"traceutil/trace.go:171","msg":"trace[1075244544] transaction","detail":"{read_only:false; response_revision:4489; number_of_response:1; }","duration":"151.765836ms","start":"2026-03-20T10:21:37.768201Z","end":"2026-03-20T10:21:37.919967Z","steps":["trace[1075244544] 'process raft request' (duration: 77.705292ms)","trace[1075244544] 'compare' (duration: 73.500273ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:21:37.920103Z","caller":"traceutil/trace.go:171","msg":"trace[563435518] transaction","detail":"{read_only:false; response_revision:4491; number_of_response:1; }","duration":"148.8034ms","start":"2026-03-20T10:21:37.771292Z","end":"2026-03-20T10:21:37.920095Z","steps":["trace[563435518] 'process raft request' (duration: 148.441883ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:21:38.057418Z","caller":"traceutil/trace.go:171","msg":"trace[521820750] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"132.203617ms","start":"2026-03-20T10:21:37.925184Z","end":"2026-03-20T10:21:38.057388Z","steps":["trace[521820750] 'process raft request' (duration: 106.667447ms)","trace[521820750] 'compare' (duration: 25.352367ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:21:38.057457Z","caller":"traceutil/trace.go:171","msg":"trace[701641834] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"130.953514ms","start":"2026-03-20T10:21:37.92648Z","end":"2026-03-20T10:21:38.057433Z","steps":["trace[701641834] 'process raft request' (duration: 130.890203ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:21:38.057511Z","caller":"traceutil/trace.go:171","msg":"trace[436853473] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"131.758719ms","start":"2026-03-20T10:21:37.925734Z","end":"2026-03-20T10:21:38.057493Z","steps":["trace[436853473] 'process raft request' (duration: 131.586826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:21:54.281345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.872878ms","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-03-20T10:21:54.281442Z","caller":"traceutil/trace.go:171","msg":"trace[1203675922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4833; }","duration":"110.022571ms","start":"2026-03-20T10:21:54.171402Z","end":"2026-03-20T10:21:54.281424Z","steps":["trace[1203675922] 'range keys from in-memory index tree' (duration: 109.667044ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:23:57.453829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2783} {"level":"info","ts":"2026-03-20T10:23:57.518951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2783,"took":"63.701702ms","hash":2136606636,"current-db-size-bytes":42135552,"current-db-size":"42 MB","current-db-size-in-use-bytes":31596544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-20T10:23:57.519033Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2136606636,"revision":2783,"compact-revision":-1} {"level":"info","ts":"2026-03-20T10:27:50.503519Z","caller":"traceutil/trace.go:171","msg":"trace[1196209611] linearizableReadLoop","detail":"{readStateIndex:7719; appliedIndex:7718; }","duration":"234.53328ms","start":"2026-03-20T10:27:50.268955Z","end":"2026-03-20T10:27:50.503488Z","steps":["trace[1196209611] 'read index received' (duration: 234.224324ms)","trace[1196209611] 'applied index is now lower than readState.Index' (duration: 307.706µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:27:50.503713Z","caller":"traceutil/trace.go:171","msg":"trace[1008519972] transaction","detail":"{read_only:false; response_revision:7445; number_of_response:1; }","duration":"257.929985ms","start":"2026-03-20T10:27:50.245641Z","end":"2026-03-20T10:27:50.503571Z","steps":["trace[1008519972] 'process raft request' (duration: 257.65327ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:27:50.504172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.165591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wxxgv\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-20T10:27:50.504251Z","caller":"traceutil/trace.go:171","msg":"trace[7205273] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wxxgv; range_end:; response_count:1; response_revision:7445; }","duration":"235.308945ms","start":"2026-03-20T10:27:50.268923Z","end":"2026-03-20T10:27:50.504231Z","steps":["trace[7205273] 'agreement among raft nodes before linearized reading' (duration: 234.686003ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:27:50.632722Z","caller":"traceutil/trace.go:171","msg":"trace[747246832] linearizableReadLoop","detail":"{readStateIndex:7720; appliedIndex:7719; }","duration":"122.760117ms","start":"2026-03-20T10:27:50.509938Z","end":"2026-03-20T10:27:50.632698Z","steps":["trace[747246832] 'read index received' (duration: 95.167034ms)","trace[747246832] 'applied index is now lower than readState.Index' (duration: 27.591533ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:27:50.633111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.158435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223813"} {"level":"info","ts":"2026-03-20T10:27:50.633147Z","caller":"traceutil/trace.go:171","msg":"trace[1035291961] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7445; }","duration":"123.245006ms","start":"2026-03-20T10:27:50.509895Z","end":"2026-03-20T10:27:50.63314Z","steps":["trace[1035291961] 'agreement among raft nodes before linearized reading' (duration: 122.926199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:27:50.633205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.231362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:27:50.633286Z","caller":"traceutil/trace.go:171","msg":"trace[790905830] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:7445; }","duration":"112.346414ms","start":"2026-03-20T10:27:50.520922Z","end":"2026-03-20T10:27:50.633268Z","steps":["trace[790905830] 'agreement among raft nodes before linearized reading' (duration: 112.216892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:03.051453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.026954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-20T10:28:03.051539Z","caller":"traceutil/trace.go:171","msg":"trace[809110335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7502; }","duration":"122.183046ms","start":"2026-03-20T10:28:02.929336Z","end":"2026-03-20T10:28:03.051519Z","steps":["trace[809110335] 'range keys from in-memory index tree' (duration: 121.84381ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:31.457533Z","caller":"traceutil/trace.go:171","msg":"trace[29728747] transaction","detail":"{read_only:false; response_revision:7696; number_of_response:1; }","duration":"187.581785ms","start":"2026-03-20T10:28:31.269916Z","end":"2026-03-20T10:28:31.457498Z","steps":["trace[29728747] 'process raft request' (duration: 187.417622ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:31.507759Z","caller":"traceutil/trace.go:171","msg":"trace[279492539] linearizableReadLoop","detail":"{readStateIndex:7983; appliedIndex:7981; }","duration":"198.162923ms","start":"2026-03-20T10:28:31.309566Z","end":"2026-03-20T10:28:31.507728Z","steps":["trace[279492539] 'read index received' (duration: 147.779314ms)","trace[279492539] 'applied index is now lower than readState.Index' (duration: 50.382359ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:28:31.507907Z","caller":"traceutil/trace.go:171","msg":"trace[1048922368] transaction","detail":"{read_only:false; response_revision:7697; number_of_response:1; }","duration":"235.573479ms","start":"2026-03-20T10:28:31.272321Z","end":"2026-03-20T10:28:31.507895Z","steps":["trace[1048922368] 'process raft request' (duration: 213.131141ms)","trace[1048922368] 'compare' (duration: 22.152723ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:28:31.50812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.112957ms","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-03-20T10:28:31.508211Z","caller":"traceutil/trace.go:171","msg":"trace[1073925141] 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:7697; }","duration":"164.221589ms","start":"2026-03-20T10:28:31.34396Z","end":"2026-03-20T10:28:31.508182Z","steps":["trace[1073925141] 'agreement among raft nodes before linearized reading' (duration: 164.042206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:31.508192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.57615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:28:31.508294Z","caller":"traceutil/trace.go:171","msg":"trace[983879066] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:7697; }","duration":"198.731093ms","start":"2026-03-20T10:28:31.309539Z","end":"2026-03-20T10:28:31.50827Z","steps":["trace[983879066] 'agreement among raft nodes before linearized reading' (duration: 198.479728ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:43.772588Z","caller":"traceutil/trace.go:171","msg":"trace[662679330] transaction","detail":"{read_only:false; response_revision:7798; number_of_response:1; }","duration":"179.329886ms","start":"2026-03-20T10:28:43.593219Z","end":"2026-03-20T10:28:43.772549Z","steps":["trace[662679330] 'process raft request' (duration: 179.120163ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:44.333738Z","caller":"traceutil/trace.go:171","msg":"trace[911786016] linearizableReadLoop","detail":"{readStateIndex:8091; appliedIndex:8090; }","duration":"131.737902ms","start":"2026-03-20T10:28:44.201969Z","end":"2026-03-20T10:28:44.333707Z","steps":["trace[911786016] 'read index received' (duration: 89.126288ms)","trace[911786016] 'applied index is now lower than readState.Index' (duration: 42.610014ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:28:44.333926Z","caller":"traceutil/trace.go:171","msg":"trace[1986134094] transaction","detail":"{read_only:false; response_revision:7802; number_of_response:1; }","duration":"216.100101ms","start":"2026-03-20T10:28:44.117781Z","end":"2026-03-20T10:28:44.333881Z","steps":["trace[1986134094] 'process raft request' (duration: 173.390686ms)","trace[1986134094] 'compare' (duration: 42.396398ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:28:44.334143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.155829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:28:44.334204Z","caller":"traceutil/trace.go:171","msg":"trace[738336634] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:7802; }","duration":"132.286851ms","start":"2026-03-20T10:28:44.201899Z","end":"2026-03-20T10:28:44.334186Z","steps":["trace[738336634] 'agreement among raft nodes before linearized reading' (duration: 131.989336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:28:44.334281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.661408ms","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-03-20T10:28:44.334326Z","caller":"traceutil/trace.go:171","msg":"trace[1879789018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7802; }","duration":"104.723359ms","start":"2026-03-20T10:28:44.229592Z","end":"2026-03-20T10:28:44.334315Z","steps":["trace[1879789018] 'agreement among raft nodes before linearized reading' (duration: 104.551056ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:28:57.471208Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5792} {"level":"info","ts":"2026-03-20T10:28:57.559685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5792,"took":"85.321797ms","hash":2332911984,"current-db-size-bytes":43712512,"current-db-size":"44 MB","current-db-size-in-use-bytes":23773184,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-20T10:28:57.559759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2332911984,"revision":5792,"compact-revision":2783} {"level":"info","ts":"2026-03-20T10:29:44.887631Z","caller":"traceutil/trace.go:171","msg":"trace[968869387] linearizableReadLoop","detail":"{readStateIndex:8713; appliedIndex:8712; }","duration":"195.528417ms","start":"2026-03-20T10:29:44.692072Z","end":"2026-03-20T10:29:44.8876Z","steps":["trace[968869387] 'read index received' (duration: 195.290453ms)","trace[968869387] 'applied index is now lower than readState.Index' (duration: 236.604µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:29:44.887921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.782547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-20T10:29:44.887955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.848785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-20T10:29:44.887989Z","caller":"traceutil/trace.go:171","msg":"trace[1060544777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8407; }","duration":"194.938367ms","start":"2026-03-20T10:29:44.693041Z","end":"2026-03-20T10:29:44.88798Z","steps":["trace[1060544777] 'agreement among raft nodes before linearized reading' (duration: 194.744073ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:44.887982Z","caller":"traceutil/trace.go:171","msg":"trace[150805679] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8407; }","duration":"142.919049ms","start":"2026-03-20T10:29:44.745049Z","end":"2026-03-20T10:29:44.887968Z","steps":["trace[150805679] 'agreement among raft nodes before linearized reading' (duration: 142.792587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:29:44.887959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.872694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-20T10:29:44.888045Z","caller":"traceutil/trace.go:171","msg":"trace[1872552678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8407; }","duration":"195.976096ms","start":"2026-03-20T10:29:44.692049Z","end":"2026-03-20T10:29:44.888025Z","steps":["trace[1872552678] 'agreement among raft nodes before linearized reading' (duration: 195.719691ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:44.887715Z","caller":"traceutil/trace.go:171","msg":"trace[279555213] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"259.156291ms","start":"2026-03-20T10:29:44.628513Z","end":"2026-03-20T10:29:44.887669Z","steps":["trace[279555213] 'process raft request' (duration: 258.882846ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:45.012216Z","caller":"traceutil/trace.go:171","msg":"trace[1491580666] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"118.26142ms","start":"2026-03-20T10:29:44.893893Z","end":"2026-03-20T10:29:45.012154Z","steps":["trace[1491580666] 'process raft request' (duration: 117.965385ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:45.012294Z","caller":"traceutil/trace.go:171","msg":"trace[1734724062] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"118.000445ms","start":"2026-03-20T10:29:44.894244Z","end":"2026-03-20T10:29:45.012244Z","steps":["trace[1734724062] 'process raft request' (duration: 117.787781ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:29:45.012304Z","caller":"traceutil/trace.go:171","msg":"trace[1699621398] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"117.534676ms","start":"2026-03-20T10:29:44.894741Z","end":"2026-03-20T10:29:45.012276Z","steps":["trace[1699621398] 'process raft request' (duration: 117.366883ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:32:41.660606Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c187bdee547e8fd1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-20T10:32:41.667079Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-20T10:32:41.667164Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-20T10:33:57.480349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7901} {"level":"info","ts":"2026-03-20T10:33:57.545265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7901,"took":"63.040625ms","hash":2338791780,"current-db-size-bytes":43712512,"current-db-size":"44 MB","current-db-size-in-use-bytes":25694208,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-20T10:33:57.545369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2338791780,"revision":7901,"compact-revision":5792} {"level":"info","ts":"2026-03-20T10:38:57.486549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10017} {"level":"info","ts":"2026-03-20T10:38:57.558758Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10017,"took":"70.286208ms","hash":1609706747,"current-db-size-bytes":43712512,"current-db-size":"44 MB","current-db-size-in-use-bytes":16678912,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-20T10:38:57.558824Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1609706747,"revision":10017,"compact-revision":7901}