{"level":"warn","ts":"2026-02-02T15:55:59.425869Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T15:55:59.425953Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-02-02T15:55:59.426033Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T15:55:59.426042Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-02T15:55:59.426061Z","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-02-02T15:55:59.426766Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-02-02T15:55:59.426879Z","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":2,"max-cpu-available":2,"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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-02-02T15:55:59.442833Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"15.639149ms"} {"level":"info","ts":"2026-02-02T15:55:59.458497Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-02-02T15:55:59.4587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T15:55:59.458797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-02-02T15:55:59.458875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T15:55:59.458927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-02-02T15:55:59.459021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-02-02T15:55:59.473408Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T15:55:59.476431Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T15:55:59.484585Z","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-02-02T15:55:59.492124Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T15:55:59.493573Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T15:55:59.509239Z","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-02-02T15:55:59.509298Z","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-02-02T15:55:59.509308Z","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-02-02T15:55:59.509643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-02-02T15:55:59.509725Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-02T15:55:59.511622Z","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-02-02T15:55:59.517528Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T15:55:59.517824Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T15:55:59.512445Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-02T15:55:59.518291Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-02T15:56:00.46019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T15:56:00.460236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T15:56:00.46026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-02-02T15:56:00.460271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-02-02T15:56:00.460276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-02T15:56:00.460289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-02-02T15:56:00.460296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-02T15:56:00.461978Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T15:56:00.463077Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T15:56:00.46313Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T15:56:00.463149Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T15:56:00.46317Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T15:56:00.463181Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T15:56:00.463396Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T15:56:00.464715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-02-02T15:56:00.466208Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T15:56:00.466228Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T15:56:00.466947Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T15:56:28.382589Z","caller":"traceutil/trace.go:171","msg":"trace[265894194] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"161.937836ms","start":"2026-02-02T15:56:28.220631Z","end":"2026-02-02T15:56:28.382569Z","steps":["trace[265894194] 'read index received' (duration: 161.740515ms)","trace[265894194] 'applied index is now lower than readState.Index' (duration: 196.621µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T15:56:28.382929Z","caller":"traceutil/trace.go:171","msg":"trace[249126823] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"287.6313ms","start":"2026-02-02T15:56:28.095284Z","end":"2026-02-02T15:56:28.382916Z","steps":["trace[249126823] 'process raft request' (duration: 287.141546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:28.383179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.534291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T15:56:28.383229Z","caller":"traceutil/trace.go:171","msg":"trace[319806688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"162.627961ms","start":"2026-02-02T15:56:28.220588Z","end":"2026-02-02T15:56:28.383216Z","steps":["trace[319806688] 'agreement among raft nodes before linearized reading' (duration: 162.548181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:28.383457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.664691ms","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-02-02T15:56:28.383492Z","caller":"traceutil/trace.go:171","msg":"trace[444178399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:462; }","duration":"135.722671ms","start":"2026-02-02T15:56:28.24776Z","end":"2026-02-02T15:56:28.383482Z","steps":["trace[444178399] 'agreement among raft nodes before linearized reading' (duration: 135.661731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:28.383803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.195957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T15:56:28.38384Z","caller":"traceutil/trace.go:171","msg":"trace[1857974839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"121.311398ms","start":"2026-02-02T15:56:28.262519Z","end":"2026-02-02T15:56:28.38383Z","steps":["trace[1857974839] 'agreement among raft nodes before linearized reading' (duration: 121.195147ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T15:56:35.536061Z","caller":"traceutil/trace.go:171","msg":"trace[1464994030] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"274.403083ms","start":"2026-02-02T15:56:35.261643Z","end":"2026-02-02T15:56:35.536046Z","steps":["trace[1464994030] 'process raft request' (duration: 274.307713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:37.721291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100118313723757,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T15:56:38.070207Z","caller":"traceutil/trace.go:171","msg":"trace[655135955] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"849.663933ms","start":"2026-02-02T15:56:37.220528Z","end":"2026-02-02T15:56:38.070192Z","steps":["trace[655135955] 'read index received' (duration: 849.508877ms)","trace[655135955] 'applied index is now lower than readState.Index' (duration: 154.546µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T15:56:38.070296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.752917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T15:56:38.070312Z","caller":"traceutil/trace.go:171","msg":"trace[207113319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"849.81685ms","start":"2026-02-02T15:56:37.22049Z","end":"2026-02-02T15:56:38.070307Z","steps":["trace[207113319] 'agreement among raft nodes before linearized reading' (duration: 849.764688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:38.070329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:37.220475Z","time spent":"849.850031ms","remote":"127.0.0.1:48576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T15:56:38.070473Z","caller":"traceutil/trace.go:171","msg":"trace[2474464] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"970.224944ms","start":"2026-02-02T15:56:37.100243Z","end":"2026-02-02T15:56:38.070468Z","steps":["trace[2474464] 'process raft request' (duration: 969.850699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:38.070754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:37.100225Z","time spent":"970.260346ms","remote":"127.0.0.1:48730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T15:56:38.976302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100118313723762,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T15:56:39.476562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100118313723762,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T15:56:39.976857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100118313723762,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T15:56:40.282151Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.856159272s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T15:56:40.282395Z","caller":"traceutil/trace.go:171","msg":"trace[1931282153] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.856446383s","start":"2026-02-02T15:56:38.425933Z","end":"2026-02-02T15:56:40.28238Z","steps":["trace[1931282153] 'process raft request' (duration: 1.856340999s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:40.282473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:38.425923Z","time spent":"1.856502776s","remote":"127.0.0.1:48818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T15:56:40.839192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.237007ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-02T15:56:40.83929Z","caller":"traceutil/trace.go:171","msg":"trace[790561512] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"2.363741035s","start":"2026-02-02T15:56:38.475532Z","end":"2026-02-02T15:56:40.839273Z","steps":["trace[790561512] 'read index received' (duration: 1.807176907s)","trace[790561512] 'applied index is now lower than readState.Index' (duration: 556.562688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T15:56:40.83936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.363819208s","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-02-02T15:56:40.839384Z","caller":"traceutil/trace.go:171","msg":"trace[560689418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"2.363870411s","start":"2026-02-02T15:56:38.475507Z","end":"2026-02-02T15:56:40.839377Z","steps":["trace[560689418] 'agreement among raft nodes before linearized reading' (duration: 2.363807268s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:40.839404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:38.475497Z","time spent":"2.363902421s","remote":"127.0.0.1:48818","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-02-02T15:56:40.839465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.341012205s","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-02-02T15:56:40.839494Z","caller":"traceutil/trace.go:171","msg":"trace[1050424470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"2.341067567s","start":"2026-02-02T15:56:38.498418Z","end":"2026-02-02T15:56:40.839485Z","steps":["trace[1050424470] 'agreement among raft nodes before linearized reading' (duration: 2.340940802s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:40.839523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:38.498406Z","time spent":"2.341111008s","remote":"127.0.0.1:48818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T15:56:40.839635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618345439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T15:56:40.839662Z","caller":"traceutil/trace.go:171","msg":"trace[427229541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.618393391s","start":"2026-02-02T15:56:39.22126Z","end":"2026-02-02T15:56:40.839654Z","steps":["trace[427229541] 'agreement among raft nodes before linearized reading' (duration: 1.618347669s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:40.839689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:39.221248Z","time spent":"1.618433392s","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T15:56:40.839701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.341112208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-02T15:56:40.839717Z","caller":"traceutil/trace.go:171","msg":"trace[1988862870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"2.341136119s","start":"2026-02-02T15:56:38.498576Z","end":"2026-02-02T15:56:40.839712Z","steps":["trace[1988862870] 'agreement among raft nodes before linearized reading' (duration: 2.341089967s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:40.83973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:38.498573Z","time spent":"2.34115412s","remote":"127.0.0.1:48818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-02T15:56:43.831034Z","caller":"traceutil/trace.go:171","msg":"trace[1606874181] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"403.20651ms","start":"2026-02-02T15:56:43.427808Z","end":"2026-02-02T15:56:43.831014Z","steps":["trace[1606874181] 'read index received' (duration: 402.995602ms)","trace[1606874181] 'applied index is now lower than readState.Index' (duration: 209.378µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T15:56:43.831143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:43.426182Z","time spent":"404.957859ms","remote":"127.0.0.1:48606","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-02T15:56:43.831348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.523482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T15:56:43.831378Z","caller":"traceutil/trace.go:171","msg":"trace[537688942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"403.571934ms","start":"2026-02-02T15:56:43.427797Z","end":"2026-02-02T15:56:43.831369Z","steps":["trace[537688942] 'agreement among raft nodes before linearized reading' (duration: 403.507952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:43.831401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:43.427792Z","time spent":"403.603545ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T15:56:43.831512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.927057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T15:56:43.831535Z","caller":"traceutil/trace.go:171","msg":"trace[570471943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:540; }","duration":"396.960079ms","start":"2026-02-02T15:56:43.434568Z","end":"2026-02-02T15:56:43.831528Z","steps":["trace[570471943] 'agreement among raft nodes before linearized reading' (duration: 396.924658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:56:43.831559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:56:43.434563Z","time spent":"396.98836ms","remote":"127.0.0.1:48818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-02T15:56:44.074173Z","caller":"traceutil/trace.go:171","msg":"trace[1987655045] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"113.338216ms","start":"2026-02-02T15:56:43.960819Z","end":"2026-02-02T15:56:44.074157Z","steps":["trace[1987655045] 'process raft request' (duration: 86.490922ms)","trace[1987655045] 'compare' (duration: 26.606545ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T15:56:44.218667Z","caller":"traceutil/trace.go:171","msg":"trace[882124424] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"137.356844ms","start":"2026-02-02T15:56:44.081297Z","end":"2026-02-02T15:56:44.218654Z","steps":["trace[882124424] 'process raft request' (duration: 128.713054ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T15:56:44.295863Z","caller":"traceutil/trace.go:171","msg":"trace[1311178671] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"160.515188ms","start":"2026-02-02T15:56:44.135336Z","end":"2026-02-02T15:56:44.295852Z","steps":["trace[1311178671] 'process raft request' (duration: 160.444695ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T15:56:44.502055Z","caller":"traceutil/trace.go:171","msg":"trace[67504962] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"104.347413ms","start":"2026-02-02T15:56:44.397689Z","end":"2026-02-02T15:56:44.502036Z","steps":["trace[67504962] 'process raft request' (duration: 91.365117ms)","trace[67504962] 'compare' (duration: 12.893643ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T15:57:55.513334Z","caller":"traceutil/trace.go:171","msg":"trace[1209770285] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"138.891658ms","start":"2026-02-02T15:57:55.374421Z","end":"2026-02-02T15:57:55.513313Z","steps":["trace[1209770285] 'process raft request' (duration: 135.951461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:58:09.231689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.034498ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-02T15:58:09.231956Z","caller":"traceutil/trace.go:171","msg":"trace[604635351] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"431.799848ms","start":"2026-02-02T15:58:08.800144Z","end":"2026-02-02T15:58:09.231944Z","steps":["trace[604635351] 'read index received' (duration: 11.446335ms)","trace[604635351] 'applied index is now lower than readState.Index' (duration: 420.352343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T15:58:09.232515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.388978ms","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-02-02T15:58:09.233549Z","caller":"traceutil/trace.go:171","msg":"trace[1434665106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"433.461417ms","start":"2026-02-02T15:58:08.800078Z","end":"2026-02-02T15:58:09.233539Z","steps":["trace[1434665106] 'agreement among raft nodes before linearized reading' (duration: 432.359228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:58:09.233708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:58:08.800061Z","time spent":"433.63612ms","remote":"127.0.0.1:48818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T15:58:09.232906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.794181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T15:58:09.235399Z","caller":"traceutil/trace.go:171","msg":"trace[1360368672] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:955; }","duration":"404.319896ms","start":"2026-02-02T15:58:08.83107Z","end":"2026-02-02T15:58:09.23539Z","steps":["trace[1360368672] 'agreement among raft nodes before linearized reading' (duration: 401.802662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T15:58:09.236867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T15:58:08.831056Z","time spent":"405.797723ms","remote":"127.0.0.1:48962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-02T15:58:45.732074Z","caller":"traceutil/trace.go:171","msg":"trace[734525780] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"125.834282ms","start":"2026-02-02T15:58:45.606217Z","end":"2026-02-02T15:58:45.732052Z","steps":["trace[734525780] 'process raft request' (duration: 119.345577ms)"],"step_count":1}