{"level":"warn","ts":"2026-05-14T07:23:05.59798Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T07:23:05.598173Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-05-14T07:23:05.598262Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T07:23:05.598278Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-14T07:23:05.598297Z","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-05-14T07:23:05.599759Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-14T07:23:05.599857Z","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://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-05-14T07:23:05.603242Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.14306ms"} {"level":"info","ts":"2026-05-14T07:23:05.610568Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-14T07:23:05.610842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T07:23:05.610864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-14T07:23:05.610878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T07:23:05.610895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-14T07:23:05.610936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-14T07:23:05.617555Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T07:23:05.619166Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T07:23:05.620175Z","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-05-14T07:23:05.622154Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T07:23:05.622288Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T07:23:05.622389Z","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-05-14T07:23:05.622434Z","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-05-14T07:23:05.622443Z","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-05-14T07:23:05.623272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-14T07:23:05.623747Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-14T07:23:05.627688Z","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-05-14T07:23:05.627971Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T07:23:05.628005Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T07:23:05.628178Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-14T07:23:05.628193Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-14T07:23:05.911714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T07:23:05.91177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T07:23:05.911806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-14T07:23:05.911822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-14T07:23:05.911831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-14T07:23:05.911849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-14T07:23:05.91186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-14T07:23:05.912554Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T07:23:05.912708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T07:23:05.912849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T07:23:05.912913Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:23:05.913126Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T07:23:05.913287Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T07:23:05.913786Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:23:05.913855Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:23:05.913874Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:23:05.916245Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-05-14T07:23:05.916812Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T07:23:42.400529Z","caller":"traceutil/trace.go:171","msg":"trace[677756303] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"183.737886ms","start":"2026-05-14T07:23:42.216766Z","end":"2026-05-14T07:23:42.400504Z","steps":["trace[677756303] 'read index received' (duration: 182.882566ms)","trace[677756303] 'applied index is now lower than readState.Index' (duration: 854.29µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:23:42.400769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.973464ms","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-05-14T07:23:42.400834Z","caller":"traceutil/trace.go:171","msg":"trace[894688326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"184.060917ms","start":"2026-05-14T07:23:42.216763Z","end":"2026-05-14T07:23:42.400824Z","steps":["trace[894688326] 'agreement among raft nodes before linearized reading' (duration: 183.889321ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:23:42.400652Z","caller":"traceutil/trace.go:171","msg":"trace[640890962] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"197.494022ms","start":"2026-05-14T07:23:42.203095Z","end":"2026-05-14T07:23:42.400589Z","steps":["trace[640890962] 'process raft request' (duration: 196.821428ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:23:43.012756Z","caller":"traceutil/trace.go:171","msg":"trace[1787575912] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"139.782643ms","start":"2026-05-14T07:23:42.872946Z","end":"2026-05-14T07:23:43.012728Z","steps":["trace[1787575912] 'process raft request' (duration: 138.58083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:23:43.632766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.385433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:23:43.632898Z","caller":"traceutil/trace.go:171","msg":"trace[1551757143] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"518.462238ms","start":"2026-05-14T07:23:43.114419Z","end":"2026-05-14T07:23:43.632881Z","steps":["trace[1551757143] 'read index received' (duration: 352.207815ms)","trace[1551757143] 'applied index is now lower than readState.Index' (duration: 166.252813ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:23:43.633045Z","caller":"traceutil/trace.go:171","msg":"trace[1311066188] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"549.457137ms","start":"2026-05-14T07:23:43.083576Z","end":"2026-05-14T07:23:43.633033Z","steps":["trace[1311066188] 'process raft request' (duration: 383.08287ms)","trace[1311066188] 'compare' (duration: 164.929866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:23:43.633131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:23:43.083554Z","time spent":"549.521449ms","remote":"127.0.0.1:57430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:23:43.633352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.951182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-14T07:23:43.633393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.945194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-14T07:23:43.63346Z","caller":"traceutil/trace.go:171","msg":"trace[533195929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"155.114348ms","start":"2026-05-14T07:23:43.478314Z","end":"2026-05-14T07:23:43.633429Z","steps":["trace[533195929] 'agreement among raft nodes before linearized reading' (duration: 154.87788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:23:43.633387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.311644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:23:43.633489Z","caller":"traceutil/trace.go:171","msg":"trace[1169756560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"519.049028ms","start":"2026-05-14T07:23:43.114415Z","end":"2026-05-14T07:23:43.633464Z","steps":["trace[1169756560] 'agreement among raft nodes before linearized reading' (duration: 518.82886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:23:43.633677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:23:43.114387Z","time spent":"519.264156ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-14T07:23:43.633394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.808927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wwfgq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-14T07:23:43.633752Z","caller":"traceutil/trace.go:171","msg":"trace[1012486951] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wwfgq; range_end:; response_count:1; response_revision:618; }","duration":"500.201951ms","start":"2026-05-14T07:23:43.133536Z","end":"2026-05-14T07:23:43.633738Z","steps":["trace[1012486951] 'agreement among raft nodes before linearized reading' (duration: 499.628051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:23:43.633831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:23:43.133515Z","time spent":"500.300435ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-wwfgq\" "} {"level":"info","ts":"2026-05-14T07:23:43.633601Z","caller":"traceutil/trace.go:171","msg":"trace[29119098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"465.571903ms","start":"2026-05-14T07:23:43.167995Z","end":"2026-05-14T07:23:43.633567Z","steps":["trace[29119098] 'agreement among raft nodes before linearized reading' (duration: 465.272842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:23:43.633976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:23:43.167977Z","time spent":"465.982908ms","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-14T07:24:06.824643Z","caller":"traceutil/trace.go:171","msg":"trace[107417632] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"315.954661ms","start":"2026-05-14T07:24:06.508661Z","end":"2026-05-14T07:24:06.824615Z","steps":["trace[107417632] 'read index received' (duration: 315.756043ms)","trace[107417632] 'applied index is now lower than readState.Index' (duration: 197.628µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:24:06.824696Z","caller":"traceutil/trace.go:171","msg":"trace[2021567021] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"566.223904ms","start":"2026-05-14T07:24:06.258443Z","end":"2026-05-14T07:24:06.824667Z","steps":["trace[2021567021] 'process raft request' (duration: 566.034897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:06.824938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.788985ms","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-05-14T07:24:06.825017Z","caller":"traceutil/trace.go:171","msg":"trace[1549844640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"315.906159ms","start":"2026-05-14T07:24:06.509097Z","end":"2026-05-14T07:24:06.825003Z","steps":["trace[1549844640] 'agreement among raft nodes before linearized reading' (duration: 315.713122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:06.825043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:06.258427Z","time spent":"566.332658ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:24:06.825055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:06.509069Z","time spent":"315.977681ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T07:24:06.824938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.263072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T07:24:06.825285Z","caller":"traceutil/trace.go:171","msg":"trace[854475070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"316.658216ms","start":"2026-05-14T07:24:06.508617Z","end":"2026-05-14T07:24:06.825275Z","steps":["trace[854475070] 'agreement among raft nodes before linearized reading' (duration: 316.170158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:24:06.82533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:24:06.508598Z","time spent":"316.723118ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T07:24:39.280288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.340241ms","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-05-14T07:24:39.280399Z","caller":"traceutil/trace.go:171","msg":"trace[1482153399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"126.481252ms","start":"2026-05-14T07:24:39.153884Z","end":"2026-05-14T07:24:39.280365Z","steps":["trace[1482153399] 'range keys from in-memory index tree' (duration: 126.15835ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:25:24.541494Z","caller":"traceutil/trace.go:171","msg":"trace[1399771770] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"152.83642ms","start":"2026-05-14T07:25:24.388638Z","end":"2026-05-14T07:25:24.541474Z","steps":["trace[1399771770] 'process raft request' (duration: 152.731119ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:26:03.318177Z","caller":"traceutil/trace.go:171","msg":"trace[325468794] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2228; }","duration":"113.931313ms","start":"2026-05-14T07:26:03.204223Z","end":"2026-05-14T07:26:03.318154Z","steps":["trace[325468794] 'read index received' (duration: 113.715118ms)","trace[325468794] 'applied index is now lower than readState.Index' (duration: 215.255µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:26:03.318384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.134259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T07:26:03.31846Z","caller":"traceutil/trace.go:171","msg":"trace[614458087] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2145; }","duration":"114.254402ms","start":"2026-05-14T07:26:03.20419Z","end":"2026-05-14T07:26:03.318444Z","steps":["trace[614458087] 'agreement among raft nodes before linearized reading' (duration: 114.133759ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:26:03.318373Z","caller":"traceutil/trace.go:171","msg":"trace[680419622] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"144.002006ms","start":"2026-05-14T07:26:03.174342Z","end":"2026-05-14T07:26:03.318344Z","steps":["trace[680419622] 'process raft request' (duration: 143.651766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:26:03.843396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.69724ms","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":"warn","ts":"2026-05-14T07:26:03.843434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.429765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:26:03.843484Z","caller":"traceutil/trace.go:171","msg":"trace[886896361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2148; }","duration":"181.807953ms","start":"2026-05-14T07:26:03.661656Z","end":"2026-05-14T07:26:03.843464Z","steps":["trace[886896361] 'range keys from in-memory index tree' (duration: 181.493824ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:26:03.843526Z","caller":"traceutil/trace.go:171","msg":"trace[1052759516] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"157.895727ms","start":"2026-05-14T07:26:03.685612Z","end":"2026-05-14T07:26:03.843508Z","steps":["trace[1052759516] 'process raft request' (duration: 51.303288ms)","trace[1052759516] 'compare' (duration: 106.335962ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:26:03.856119Z","caller":"traceutil/trace.go:171","msg":"trace[1512505412] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"166.839634ms","start":"2026-05-14T07:26:03.689265Z","end":"2026-05-14T07:26:03.856104Z","steps":["trace[1512505412] 'process raft request' (duration: 166.758291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:26:55.108812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.915865ms","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-05-14T07:26:55.108911Z","caller":"traceutil/trace.go:171","msg":"trace[1479202818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2529; }","duration":"109.059449ms","start":"2026-05-14T07:26:54.999829Z","end":"2026-05-14T07:26:55.108888Z","steps":["trace[1479202818] 'range keys from in-memory index tree' (duration: 108.767191ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:28.041321Z","caller":"traceutil/trace.go:171","msg":"trace[509733430] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"108.291529ms","start":"2026-05-14T07:27:27.933009Z","end":"2026-05-14T07:27:28.041301Z","steps":["trace[509733430] 'process raft request' (duration: 108.172726ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:28.047274Z","caller":"traceutil/trace.go:171","msg":"trace[1034645595] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"113.253374ms","start":"2026-05-14T07:27:27.934007Z","end":"2026-05-14T07:27:28.04726Z","steps":["trace[1034645595] 'process raft request' (duration: 113.182562ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:27:28.293228Z","caller":"traceutil/trace.go:171","msg":"trace[465461600] linearizableReadLoop","detail":"{readStateIndex:2820; appliedIndex:2818; }","duration":"127.47365ms","start":"2026-05-14T07:27:28.165734Z","end":"2026-05-14T07:27:28.293208Z","steps":["trace[465461600] 'read index received' (duration: 7.255416ms)","trace[465461600] 'applied index is now lower than readState.Index' (duration: 120.216874ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:27:28.293285Z","caller":"traceutil/trace.go:171","msg":"trace[165031522] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"158.098178ms","start":"2026-05-14T07:27:28.135148Z","end":"2026-05-14T07:27:28.293246Z","steps":["trace[165031522] 'process raft request' (duration: 157.952873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:27:28.293423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.672117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:27:28.293609Z","caller":"traceutil/trace.go:171","msg":"trace[1187705866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2716; }","duration":"127.883173ms","start":"2026-05-14T07:27:28.165682Z","end":"2026-05-14T07:27:28.293565Z","steps":["trace[1187705866] 'agreement among raft nodes before linearized reading' (duration: 127.615705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:31:14.905834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.097641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:31:14.905944Z","caller":"traceutil/trace.go:171","msg":"trace[1603537263] linearizableReadLoop","detail":"{readStateIndex:4807; appliedIndex:4806; }","duration":"135.628011ms","start":"2026-05-14T07:31:14.770299Z","end":"2026-05-14T07:31:14.905927Z","steps":["trace[1603537263] 'read index received' (duration: 17.383517ms)","trace[1603537263] 'applied index is now lower than readState.Index' (duration: 118.242994ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:31:14.906152Z","caller":"traceutil/trace.go:171","msg":"trace[624525475] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"136.482534ms","start":"2026-05-14T07:31:14.769546Z","end":"2026-05-14T07:31:14.906029Z","steps":["trace[624525475] 'process raft request' (duration: 18.122136ms)","trace[624525475] 'compare' (duration: 117.946627ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:31:14.906188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.872108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125596"} {"level":"info","ts":"2026-05-14T07:31:14.906396Z","caller":"traceutil/trace.go:171","msg":"trace[1123861679] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4630; }","duration":"136.054992ms","start":"2026-05-14T07:31:14.770282Z","end":"2026-05-14T07:31:14.906336Z","steps":["trace[1123861679] 'agreement among raft nodes before linearized reading' (duration: 135.690973ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:14.915399Z","caller":"traceutil/trace.go:171","msg":"trace[914733709] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"144.999307ms","start":"2026-05-14T07:31:14.770388Z","end":"2026-05-14T07:31:14.915388Z","steps":["trace[914733709] 'process raft request' (duration: 144.76821ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:14.915456Z","caller":"traceutil/trace.go:171","msg":"trace[235949914] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"144.979826ms","start":"2026-05-14T07:31:14.770433Z","end":"2026-05-14T07:31:14.915413Z","steps":["trace[235949914] 'process raft request' (duration: 144.913585ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:15.10854Z","caller":"traceutil/trace.go:171","msg":"trace[1532660352] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"111.079941ms","start":"2026-05-14T07:31:14.997438Z","end":"2026-05-14T07:31:15.108518Z","steps":["trace[1532660352] 'process raft request' (duration: 110.899366ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:15.108648Z","caller":"traceutil/trace.go:171","msg":"trace[795864502] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"129.759502ms","start":"2026-05-14T07:31:14.978866Z","end":"2026-05-14T07:31:15.108626Z","steps":["trace[795864502] 'process raft request' (duration: 105.306792ms)","trace[795864502] 'compare' (duration: 23.915836ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:31:15.388619Z","caller":"traceutil/trace.go:171","msg":"trace[1592860469] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"134.171915ms","start":"2026-05-14T07:31:15.254412Z","end":"2026-05-14T07:31:15.388584Z","steps":["trace[1592860469] 'process raft request' (duration: 118.453495ms)","trace[1592860469] 'compare' (duration: 15.533555ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:31:15.388786Z","caller":"traceutil/trace.go:171","msg":"trace[1023907926] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"134.314629ms","start":"2026-05-14T07:31:15.254452Z","end":"2026-05-14T07:31:15.388767Z","steps":["trace[1023907926] 'process raft request' (duration: 134.064452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:31:15.389152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.513188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-05-14T07:31:15.389219Z","caller":"traceutil/trace.go:171","msg":"trace[61212786] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4651; }","duration":"117.616491ms","start":"2026-05-14T07:31:15.271591Z","end":"2026-05-14T07:31:15.389208Z","steps":["trace[61212786] 'agreement among raft nodes before linearized reading' (duration: 117.483168ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:15.61032Z","caller":"traceutil/trace.go:171","msg":"trace[1859628745] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"137.790784ms","start":"2026-05-14T07:31:15.472506Z","end":"2026-05-14T07:31:15.610297Z","steps":["trace[1859628745] 'process raft request' (duration: 137.558507ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:15.61032Z","caller":"traceutil/trace.go:171","msg":"trace[2018946645] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"132.139879ms","start":"2026-05-14T07:31:15.478148Z","end":"2026-05-14T07:31:15.610288Z","steps":["trace[2018946645] 'process raft request' (duration: 132.070497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:31:15.611161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.884619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-14T07:31:15.611259Z","caller":"traceutil/trace.go:171","msg":"trace[461616162] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4660; }","duration":"114.014413ms","start":"2026-05-14T07:31:15.497228Z","end":"2026-05-14T07:31:15.611242Z","steps":["trace[461616162] 'agreement among raft nodes before linearized reading' (duration: 113.512289ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:27.811049Z","caller":"traceutil/trace.go:171","msg":"trace[1361079021] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"129.588589ms","start":"2026-05-14T07:31:27.681438Z","end":"2026-05-14T07:31:27.811027Z","steps":["trace[1361079021] 'process raft request' (duration: 129.452434ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:28.271011Z","caller":"traceutil/trace.go:171","msg":"trace[1592465670] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5059; }","duration":"105.919744ms","start":"2026-05-14T07:31:28.165075Z","end":"2026-05-14T07:31:28.270995Z","steps":["trace[1592465670] 'read index received' (duration: 105.724028ms)","trace[1592465670] 'applied index is now lower than readState.Index' (duration: 194.886µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:31:28.271125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.907164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:31:28.271144Z","caller":"traceutil/trace.go:171","msg":"trace[132825480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4877; }","duration":"105.964416ms","start":"2026-05-14T07:31:28.165174Z","end":"2026-05-14T07:31:28.271138Z","steps":["trace[132825480] 'agreement among raft nodes before linearized reading' (duration: 105.912934ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:31:28.27111Z","caller":"traceutil/trace.go:171","msg":"trace[86441825] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"123.931564ms","start":"2026-05-14T07:31:28.147137Z","end":"2026-05-14T07:31:28.271069Z","steps":["trace[86441825] 'process raft request' (duration: 123.708038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:31:28.271253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.159321ms","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-05-14T07:31:28.271312Z","caller":"traceutil/trace.go:171","msg":"trace[139172500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4877; }","duration":"106.273394ms","start":"2026-05-14T07:31:28.165025Z","end":"2026-05-14T07:31:28.271298Z","steps":["trace[139172500] 'agreement among raft nodes before linearized reading' (duration: 106.065398ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:33:06.32978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2915} {"level":"info","ts":"2026-05-14T07:33:06.425852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2915,"took":"94.505657ms","hash":459326845,"current-db-size-bytes":54341632,"current-db-size":"54 MB","current-db-size-in-use-bytes":29560832,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-14T07:33:06.425905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":459326845,"revision":2915,"compact-revision":-1} {"level":"info","ts":"2026-05-14T07:38:06.335183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5636} {"level":"info","ts":"2026-05-14T07:38:06.422362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5636,"took":"85.712831ms","hash":2231406616,"current-db-size-bytes":54341632,"current-db-size":"54 MB","current-db-size-in-use-bytes":18341888,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-14T07:38:06.422477Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2231406616,"revision":5636,"compact-revision":2915}