{"level":"warn","ts":"2026-07-24T04:29:46.439929Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:29:46.440013Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-07-24T04:29:46.440077Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:29:46.440089Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-24T04:29:46.440106Z","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-07-24T04:29:46.44083Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-07-24T04:29:46.440919Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-07-24T04:29:46.445741Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.566808ms"} {"level":"info","ts":"2026-07-24T04:29:46.452562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-07-24T04:29:46.452623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:29:46.452644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-07-24T04:29:46.452652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T04:29:46.452659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-07-24T04:29:46.452687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-07-24T04:29:46.455632Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:29:46.456925Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:29:46.458111Z","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-07-24T04:29:46.459961Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T04:29:46.460333Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T04:29:46.460424Z","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-07-24T04:29:46.460515Z","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-07-24T04:29:46.46054Z","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-07-24T04:29:46.462297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-07-24T04:29:46.46715Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-07-24T04:29:46.468397Z","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-07-24T04:29:46.468546Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-24T04:29:46.468591Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-07-24T04:29:46.468766Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T04:29:46.468825Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:29:47.453317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:29:47.453637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:29:47.453818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-07-24T04:29:47.453939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:29:47.454073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-24T04:29:47.454172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-07-24T04:29:47.454264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-07-24T04:29:47.455767Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T04:29:47.455817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:29:47.455835Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:29:47.455746Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:29:47.456649Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:29:47.456775Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:29:47.456849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:29:47.456958Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:29:47.457014Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:29:47.459319Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T04:29:47.462903Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"warn","ts":"2026-07-24T04:29:51.748767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.855152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:29:51.748977Z","caller":"traceutil/trace.go:171","msg":"trace[681232476] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:214; }","duration":"218.943184ms","start":"2026-07-24T04:29:51.530016Z","end":"2026-07-24T04:29:51.748959Z","steps":["trace[681232476] 'read index received' (duration: 26.595773ms)","trace[681232476] 'applied index is now lower than readState.Index' (duration: 192.346221ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:29:51.7491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.083817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-07-24T04:29:51.749132Z","caller":"traceutil/trace.go:171","msg":"trace[1055311611] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:210; }","duration":"219.153228ms","start":"2026-07-24T04:29:51.529969Z","end":"2026-07-24T04:29:51.749123Z","steps":["trace[1055311611] 'agreement among raft nodes before linearized reading' (duration: 219.058656ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:29:51.74933Z","caller":"traceutil/trace.go:171","msg":"trace[1241200387] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"315.752905ms","start":"2026-07-24T04:29:51.433542Z","end":"2026-07-24T04:29:51.749295Z","steps":["trace[1241200387] 'process raft request' (duration: 122.898765ms)","trace[1241200387] 'compare' (duration: 191.71561ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:29:51.74938Z","caller":"traceutil/trace.go:171","msg":"trace[65798446] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"313.262393ms","start":"2026-07-24T04:29:51.436107Z","end":"2026-07-24T04:29:51.74937Z","steps":["trace[65798446] 'process raft request' (duration: 312.787915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:29:51.749534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:29:51.433487Z","time spent":"315.952608ms","remote":"127.0.0.1:45660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:29:51.749628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:29:51.436095Z","time spent":"313.491298ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:29:51.875961Z","caller":"traceutil/trace.go:171","msg":"trace[998439149] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:216; }","duration":"121.96133ms","start":"2026-07-24T04:29:51.753974Z","end":"2026-07-24T04:29:51.875935Z","steps":["trace[998439149] 'read index received' (duration: 121.807738ms)","trace[998439149] 'applied index is now lower than readState.Index' (duration: 152.332µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:29:51.876Z","caller":"traceutil/trace.go:171","msg":"trace[1602637437] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"122.095762ms","start":"2026-07-24T04:29:51.753868Z","end":"2026-07-24T04:29:51.875964Z","steps":["trace[1602637437] 'process raft request' (duration: 121.933079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:29:51.876145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.152673ms","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-07-24T04:29:51.876204Z","caller":"traceutil/trace.go:171","msg":"trace[714081437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:211; }","duration":"122.267236ms","start":"2026-07-24T04:29:51.753924Z","end":"2026-07-24T04:29:51.876191Z","steps":["trace[714081437] 'agreement among raft nodes before linearized reading' (duration: 122.119763ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:29:51.877796Z","caller":"traceutil/trace.go:171","msg":"trace[1772554129] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"120.829872ms","start":"2026-07-24T04:29:51.756942Z","end":"2026-07-24T04:29:51.877772Z","steps":["trace[1772554129] 'process raft request' (duration: 120.65615ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:30:33.016715Z","caller":"traceutil/trace.go:171","msg":"trace[643529524] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"161.359424ms","start":"2026-07-24T04:30:32.855331Z","end":"2026-07-24T04:30:33.01669Z","steps":["trace[643529524] 'read index received' (duration: 160.599863ms)","trace[643529524] 'applied index is now lower than readState.Index' (duration: 758.781µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:30:33.016856Z","caller":"traceutil/trace.go:171","msg":"trace[875195261] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"252.297292ms","start":"2026-07-24T04:30:32.764517Z","end":"2026-07-24T04:30:33.016815Z","steps":["trace[875195261] 'process raft request' (duration: 251.631163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:30:33.017033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.677283ms","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-07-24T04:30:33.017097Z","caller":"traceutil/trace.go:171","msg":"trace[81814541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"161.792676ms","start":"2026-07-24T04:30:32.855287Z","end":"2026-07-24T04:30:33.01708Z","steps":["trace[81814541] 'agreement among raft nodes before linearized reading' (duration: 161.494728ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:30:33.211319Z","caller":"traceutil/trace.go:171","msg":"trace[461387357] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"112.867945ms","start":"2026-07-24T04:30:33.098421Z","end":"2026-07-24T04:30:33.211289Z","steps":["trace[461387357] 'process raft request' (duration: 73.917546ms)","trace[461387357] 'compare' (duration: 38.791515ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:30:33.839123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.894125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-86q66\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-24T04:30:33.839232Z","caller":"traceutil/trace.go:171","msg":"trace[1087640635] range","detail":"{range_begin:/registry/pods/kube-system/cilium-86q66; range_end:; response_count:1; response_revision:637; }","duration":"388.07993ms","start":"2026-07-24T04:30:33.451124Z","end":"2026-07-24T04:30:33.839204Z","steps":["trace[1087640635] 'range keys from in-memory index tree' (duration: 387.636109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:30:33.839228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.00784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-24T04:30:33.839293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:30:33.451097Z","time spent":"388.180063ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-86q66\" "} {"level":"info","ts":"2026-07-24T04:30:33.839585Z","caller":"traceutil/trace.go:171","msg":"trace[561898998] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"210.14505ms","start":"2026-07-24T04:30:33.629414Z","end":"2026-07-24T04:30:33.839559Z","steps":["trace[561898998] 'process raft request' (duration: 203.966415ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:30:33.83974Z","caller":"traceutil/trace.go:171","msg":"trace[332527195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"500.083142ms","start":"2026-07-24T04:30:33.339203Z","end":"2026-07-24T04:30:33.839286Z","steps":["trace[332527195] 'range keys from in-memory index tree' (duration: 499.766823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:30:33.839854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:30:33.339141Z","time spent":"500.661189ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T04:32:01.788881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.030631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:32:01.789004Z","caller":"traceutil/trace.go:171","msg":"trace[949955518] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"212.98076ms","start":"2026-07-24T04:32:01.576007Z","end":"2026-07-24T04:32:01.788988Z","steps":["trace[949955518] 'read index received' (duration: 55.587005ms)","trace[949955518] 'applied index is now lower than readState.Index' (duration: 157.390525ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:32:01.789034Z","caller":"traceutil/trace.go:171","msg":"trace[519132158] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"245.682006ms","start":"2026-07-24T04:32:01.543319Z","end":"2026-07-24T04:32:01.789001Z","steps":["trace[519132158] 'process raft request' (duration: 88.321743ms)","trace[519132158] 'compare' (duration: 156.882394ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:32:01.789134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.123235ms","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-07-24T04:32:01.789165Z","caller":"traceutil/trace.go:171","msg":"trace[1459289950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1145; }","duration":"213.195878ms","start":"2026-07-24T04:32:01.575958Z","end":"2026-07-24T04:32:01.789154Z","steps":["trace[1459289950] 'agreement among raft nodes before linearized reading' (duration: 213.075204ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:32:02.545098Z","caller":"traceutil/trace.go:171","msg":"trace[1257628206] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"231.690438ms","start":"2026-07-24T04:32:02.313387Z","end":"2026-07-24T04:32:02.545078Z","steps":["trace[1257628206] 'process raft request' (duration: 231.189158ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:33:39.172008Z","caller":"traceutil/trace.go:171","msg":"trace[256230365] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"104.618002ms","start":"2026-07-24T04:33:39.06736Z","end":"2026-07-24T04:33:39.171978Z","steps":["trace[256230365] 'process raft request' (duration: 104.469528ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:33:39.299778Z","caller":"traceutil/trace.go:171","msg":"trace[2142620464] linearizableReadLoop","detail":"{readStateIndex:1527; appliedIndex:1525; }","duration":"230.330494ms","start":"2026-07-24T04:33:39.069421Z","end":"2026-07-24T04:33:39.299751Z","steps":["trace[2142620464] 'read index received' (duration: 102.504682ms)","trace[2142620464] 'applied index is now lower than readState.Index' (duration: 127.824772ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:33:39.299934Z","caller":"traceutil/trace.go:171","msg":"trace[1816905589] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"230.97272ms","start":"2026-07-24T04:33:39.068951Z","end":"2026-07-24T04:33:39.299923Z","steps":["trace[1816905589] 'process raft request' (duration: 230.646803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:33:39.300129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.693763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:33:39.300178Z","caller":"traceutil/trace.go:171","msg":"trace[1785917292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1465; }","duration":"230.801776ms","start":"2026-07-24T04:33:39.069364Z","end":"2026-07-24T04:33:39.300166Z","steps":["trace[1785917292] 'agreement among raft nodes before linearized reading' (duration: 230.721614ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:33:39.446378Z","caller":"traceutil/trace.go:171","msg":"trace[276848346] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"142.802533ms","start":"2026-07-24T04:33:39.303544Z","end":"2026-07-24T04:33:39.446347Z","steps":["trace[276848346] 'process raft request' (duration: 129.778469ms)","trace[276848346] 'compare' (duration: 12.548903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:34:03.804958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.731201ms","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-07-24T04:34:03.805051Z","caller":"traceutil/trace.go:171","msg":"trace[357676993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1544; }","duration":"236.875465ms","start":"2026-07-24T04:34:03.568156Z","end":"2026-07-24T04:34:03.805031Z","steps":["trace[357676993] 'range keys from in-memory index tree' (duration: 236.555467ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:03.805404Z","caller":"traceutil/trace.go:171","msg":"trace[1120477353] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"188.457164ms","start":"2026-07-24T04:34:03.616933Z","end":"2026-07-24T04:34:03.80539Z","steps":["trace[1120477353] 'process raft request' (duration: 124.772629ms)","trace[1120477353] 'compare' (duration: 63.103062ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:38:49.533164Z","caller":"traceutil/trace.go:171","msg":"trace[1087354120] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"132.298549ms","start":"2026-07-24T04:38:49.400821Z","end":"2026-07-24T04:38:49.533119Z","steps":["trace[1087354120] 'process raft request' (duration: 132.020701ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:38:49.907966Z","caller":"traceutil/trace.go:171","msg":"trace[1044668562] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"140.558503ms","start":"2026-07-24T04:38:49.767382Z","end":"2026-07-24T04:38:49.907941Z","steps":["trace[1044668562] 'process raft request' (duration: 140.418219ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:14.156396Z","caller":"traceutil/trace.go:171","msg":"trace[457004270] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"150.05759ms","start":"2026-07-24T04:39:14.006321Z","end":"2026-07-24T04:39:14.156379Z","steps":["trace[457004270] 'process raft request' (duration: 149.943998ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:14.296294Z","caller":"traceutil/trace.go:171","msg":"trace[562104197] transaction","detail":"{read_only:false; response_revision:2567; number_of_response:1; }","duration":"125.255515ms","start":"2026-07-24T04:39:14.17101Z","end":"2026-07-24T04:39:14.296265Z","steps":["trace[562104197] 'process raft request' (duration: 125.088241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:31.687053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.587448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T04:39:31.687138Z","caller":"traceutil/trace.go:171","msg":"trace[1782969846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2674; }","duration":"185.712671ms","start":"2026-07-24T04:39:31.501407Z","end":"2026-07-24T04:39:31.68712Z","steps":["trace[1782969846] 'range keys from in-memory index tree' (duration: 185.360592ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:47.488623Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1688} {"level":"info","ts":"2026-07-24T04:39:47.523884Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1688,"took":"34.852871ms","hash":2328717771,"current-db-size-bytes":24166400,"current-db-size":"24 MB","current-db-size-in-use-bytes":5943296,"current-db-size-in-use":"5.9 MB"} {"level":"info","ts":"2026-07-24T04:39:47.523948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2328717771,"revision":1688,"compact-revision":-1} {"level":"info","ts":"2026-07-24T04:39:47.886076Z","caller":"traceutil/trace.go:171","msg":"trace[1524426864] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2884; }","duration":"136.733166ms","start":"2026-07-24T04:39:47.749309Z","end":"2026-07-24T04:39:47.886042Z","steps":["trace[1524426864] 'read index received' (duration: 136.457439ms)","trace[1524426864] 'applied index is now lower than readState.Index' (duration: 274.547µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:47.886191Z","caller":"traceutil/trace.go:171","msg":"trace[1166437133] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"141.34514ms","start":"2026-07-24T04:39:47.744803Z","end":"2026-07-24T04:39:47.886148Z","steps":["trace[1166437133] 'process raft request' (duration: 141.027442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:47.886322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.991222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-07-24T04:39:47.886403Z","caller":"traceutil/trace.go:171","msg":"trace[1348967549] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2746; }","duration":"137.113125ms","start":"2026-07-24T04:39:47.749271Z","end":"2026-07-24T04:39:47.886385Z","steps":["trace[1348967549] 'agreement among raft nodes before linearized reading' (duration: 136.920401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:47.886404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.494565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T04:39:47.886467Z","caller":"traceutil/trace.go:171","msg":"trace[106783598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2746; }","duration":"103.605668ms","start":"2026-07-24T04:39:47.782841Z","end":"2026-07-24T04:39:47.886446Z","steps":["trace[106783598] 'agreement among raft nodes before linearized reading' (duration: 103.390923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:48.099942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.091342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:39:48.100071Z","caller":"traceutil/trace.go:171","msg":"trace[1411157965] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"210.396724ms","start":"2026-07-24T04:39:47.88965Z","end":"2026-07-24T04:39:48.100047Z","steps":["trace[1411157965] 'process raft request' (duration: 26.098287ms)","trace[1411157965] 'compare' (duration: 183.957309ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:48.121084Z","caller":"traceutil/trace.go:171","msg":"trace[170986961] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"226.303042ms","start":"2026-07-24T04:39:47.894751Z","end":"2026-07-24T04:39:48.121054Z","steps":["trace[170986961] 'process raft request' (duration: 226.161959ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:48.121659Z","caller":"traceutil/trace.go:171","msg":"trace[1115780170] linearizableReadLoop","detail":"{readStateIndex:2887; appliedIndex:2887; }","duration":"123.508053ms","start":"2026-07-24T04:39:47.998135Z","end":"2026-07-24T04:39:48.121643Z","steps":["trace[1115780170] 'read index received' (duration: 123.499893ms)","trace[1115780170] 'applied index is now lower than readState.Index' (duration: 6.9µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:39:48.121839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.690837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:39:48.121877Z","caller":"traceutil/trace.go:171","msg":"trace[1357227077] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2748; }","duration":"123.7813ms","start":"2026-07-24T04:39:47.998086Z","end":"2026-07-24T04:39:48.121867Z","steps":["trace[1357227077] 'agreement among raft nodes before linearized reading' (duration: 123.677237ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:48.854856Z","caller":"traceutil/trace.go:171","msg":"trace[1447446379] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"151.288131ms","start":"2026-07-24T04:39:48.703544Z","end":"2026-07-24T04:39:48.854832Z","steps":["trace[1447446379] 'process raft request' (duration: 151.172168ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:48.993955Z","caller":"traceutil/trace.go:171","msg":"trace[1833690280] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"133.581458ms","start":"2026-07-24T04:39:48.860357Z","end":"2026-07-24T04:39:48.993939Z","steps":["trace[1833690280] 'process raft request' (duration: 133.556008ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:48.994096Z","caller":"traceutil/trace.go:171","msg":"trace[1356726530] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"288.516469ms","start":"2026-07-24T04:39:48.705574Z","end":"2026-07-24T04:39:48.994091Z","steps":["trace[1356726530] 'process raft request' (duration: 266.768709ms)","trace[1356726530] 'compare' (duration: 21.263158ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:48.994159Z","caller":"traceutil/trace.go:171","msg":"trace[1363899895] linearizableReadLoop","detail":"{readStateIndex:2894; appliedIndex:2893; }","duration":"135.391223ms","start":"2026-07-24T04:39:48.858763Z","end":"2026-07-24T04:39:48.994154Z","steps":["trace[1363899895] 'read index received' (duration: 113.589861ms)","trace[1363899895] 'applied index is now lower than readState.Index' (duration: 21.800802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:39:48.994255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.487116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-24T04:39:48.994278Z","caller":"traceutil/trace.go:171","msg":"trace[434580973] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2756; }","duration":"135.548566ms","start":"2026-07-24T04:39:48.858724Z","end":"2026-07-24T04:39:48.994272Z","steps":["trace[434580973] 'agreement among raft nodes before linearized reading' (duration: 135.451494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:54.200943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.720198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-24T04:39:54.201167Z","caller":"traceutil/trace.go:171","msg":"trace[229133168] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2789; }","duration":"175.989365ms","start":"2026-07-24T04:39:54.025157Z","end":"2026-07-24T04:39:54.201146Z","steps":["trace[229133168] 'range keys from in-memory index tree' (duration: 175.623185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:54.201225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.493039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:54.201333Z","caller":"traceutil/trace.go:171","msg":"trace[818785099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2789; }","duration":"131.646602ms","start":"2026-07-24T04:39:54.06966Z","end":"2026-07-24T04:39:54.201307Z","steps":["trace[818785099] 'range keys from in-memory index tree' (duration: 131.432347ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:54.321801Z","caller":"traceutil/trace.go:171","msg":"trace[311492694] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"115.137288ms","start":"2026-07-24T04:39:54.206639Z","end":"2026-07-24T04:39:54.321776Z","steps":["trace[311492694] 'process raft request' (duration: 104.442117ms)","trace[311492694] 'compare' (duration: 10.523867ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:44:47.495056Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2745} {"level":"info","ts":"2026-07-24T04:44:47.514486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2745,"took":"18.776056ms","hash":3159878712,"current-db-size-bytes":24166400,"current-db-size":"24 MB","current-db-size-in-use-bytes":6447104,"current-db-size-in-use":"6.4 MB"} {"level":"info","ts":"2026-07-24T04:44:47.514561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3159878712,"revision":2745,"compact-revision":1688}