{"level":"warn","ts":"2026-02-05T05:21:39.917226Z","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-05T05:21:39.918245Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-05T05:21:39.918334Z","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-05T05:21:39.918361Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-05T05:21:39.918383Z","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-05T05:21:39.91907Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-02-05T05:21:39.919169Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-05T05:21:39.926779Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.286511ms"} {"level":"info","ts":"2026-02-05T05:21:39.934459Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-02-05T05:21:39.934527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T05:21:39.934558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-02-05T05:21:39.934569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T05:21:39.934578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-02-05T05:21:39.93464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-02-05T05:21:39.942852Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T05:21:39.945869Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T05:21:39.948514Z","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-05T05:21:39.95235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T05:21:39.958488Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T05:21:39.961256Z","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-05T05:21:39.964594Z","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-05T05:21:39.964616Z","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-05T05:21:39.971337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-02-05T05:21:39.971439Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-05T05:21:39.972762Z","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-05T05:21:39.972963Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T05:21:39.972985Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T05:21:39.973057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-05T05:21:39.97307Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-05T05:21:40.035033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T05:21:40.035106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T05:21:40.035127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-02-05T05:21:40.035138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-02-05T05:21:40.035147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-05T05:21:40.035156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-02-05T05:21:40.035186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-05T05:21:40.038321Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:21:40.041566Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T05:21:40.041718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:21:40.04362Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-02-05T05:21:40.044019Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:21:40.044092Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:21:40.044113Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:21:40.047611Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:21:40.053295Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T05:21:40.05339Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T05:21:40.053402Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T05:21:49.627773Z","caller":"traceutil/trace.go:171","msg":"trace[1326289844] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"119.270719ms","start":"2026-02-05T05:21:49.508486Z","end":"2026-02-05T05:21:49.627757Z","steps":["trace[1326289844] 'process raft request' (duration: 118.885435ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:56.211797Z","caller":"traceutil/trace.go:171","msg":"trace[316420840] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"587.351852ms","start":"2026-02-05T05:21:55.624423Z","end":"2026-02-05T05:21:56.211775Z","steps":["trace[316420840] 'process raft request' (duration: 587.224973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:56.212359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:55.624405Z","time spent":"587.459629ms","remote":"127.0.0.1:37754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:21:56.212897Z","caller":"traceutil/trace.go:171","msg":"trace[258654489] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:424; }","duration":"360.016866ms","start":"2026-02-05T05:21:55.852817Z","end":"2026-02-05T05:21:56.212834Z","steps":["trace[258654489] 'read index received' (duration: 360.012865ms)","trace[258654489] 'applied index is now lower than readState.Index' (duration: 3.321µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:21:56.213416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.602545ms","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-05T05:21:56.213448Z","caller":"traceutil/trace.go:171","msg":"trace[20442730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"360.670239ms","start":"2026-02-05T05:21:55.852771Z","end":"2026-02-05T05:21:56.213441Z","steps":["trace[20442730] 'agreement among raft nodes before linearized reading' (duration: 360.23232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:56.213468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:55.852751Z","time spent":"360.712231ms","remote":"127.0.0.1:37822","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-05T05:21:56.216391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.46009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-02-05T05:21:56.216426Z","caller":"traceutil/trace.go:171","msg":"trace[1653736107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"172.531454ms","start":"2026-02-05T05:21:56.043886Z","end":"2026-02-05T05:21:56.216417Z","steps":["trace[1653736107] 'agreement among raft nodes before linearized reading' (duration: 172.458769ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:56.216593Z","caller":"traceutil/trace.go:171","msg":"trace[191355704] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"356.792613ms","start":"2026-02-05T05:21:55.859793Z","end":"2026-02-05T05:21:56.216586Z","steps":["trace[191355704] 'process raft request' (duration: 356.488603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:56.216645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:55.859735Z","time spent":"356.881549ms","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:21:56.216753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.375126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-05T05:21:56.216776Z","caller":"traceutil/trace.go:171","msg":"trace[456047372] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:415; }","duration":"125.425328ms","start":"2026-02-05T05:21:56.091345Z","end":"2026-02-05T05:21:56.216771Z","steps":["trace[456047372] 'agreement among raft nodes before linearized reading' (duration: 125.385866ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:22:17.701578Z","caller":"traceutil/trace.go:171","msg":"trace[549097077] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"136.928147ms","start":"2026-02-05T05:22:17.56462Z","end":"2026-02-05T05:22:17.701548Z","steps":["trace[549097077] 'read index received' (duration: 136.761295ms)","trace[549097077] 'applied index is now lower than readState.Index' (duration: 165.622µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:22:17.701741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.079938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:17.70177Z","caller":"traceutil/trace.go:171","msg":"trace[448489482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"137.169185ms","start":"2026-02-05T05:22:17.564592Z","end":"2026-02-05T05:22:17.701761Z","steps":["trace[448489482] 'agreement among raft nodes before linearized reading' (duration: 137.074058ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:22:17.70194Z","caller":"traceutil/trace.go:171","msg":"trace[1389035301] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"316.463793ms","start":"2026-02-05T05:22:17.385447Z","end":"2026-02-05T05:22:17.701911Z","steps":["trace[1389035301] 'process raft request' (duration: 315.924873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:17.702147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:17.385411Z","time spent":"316.638666ms","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:22:19.06578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289308837765922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:22:19.566908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289308837765922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:22:19.637464Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185163364s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:22:19.638734Z","caller":"traceutil/trace.go:171","msg":"trace[901893629] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.268970358s","start":"2026-02-05T05:22:18.369745Z","end":"2026-02-05T05:22:19.638715Z","steps":["trace[901893629] 'process raft request' (duration: 1.268881972s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:19.638839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:18.369725Z","time spent":"1.269059475s","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:22:19.673774Z","caller":"traceutil/trace.go:171","msg":"trace[433668431] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"1.108478243s","start":"2026-02-05T05:22:18.565278Z","end":"2026-02-05T05:22:19.673756Z","steps":["trace[433668431] 'read index received' (duration: 1.073777359s)","trace[433668431] 'applied index is now lower than readState.Index' (duration: 34.699904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:22:19.673892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108602242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:19.67391Z","caller":"traceutil/trace.go:171","msg":"trace[352868501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.108669697s","start":"2026-02-05T05:22:18.565234Z","end":"2026-02-05T05:22:19.673904Z","steps":["trace[352868501] 'agreement among raft nodes before linearized reading' (duration: 1.108614723s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:19.673929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:18.565214Z","time spent":"1.108711431s","remote":"127.0.0.1:37586","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-05T05:22:19.674271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045905303s","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-05T05:22:19.674286Z","caller":"traceutil/trace.go:171","msg":"trace[850418972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.045929104s","start":"2026-02-05T05:22:18.628352Z","end":"2026-02-05T05:22:19.674281Z","steps":["trace[850418972] 'agreement among raft nodes before linearized reading' (duration: 1.045856108s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:19.6743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:18.628345Z","time spent":"1.045950236s","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:22:19.67437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.653105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:19.674385Z","caller":"traceutil/trace.go:171","msg":"trace[2109658541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"441.684247ms","start":"2026-02-05T05:22:19.232695Z","end":"2026-02-05T05:22:19.67438Z","steps":["trace[2109658541] 'agreement among raft nodes before linearized reading' (duration: 441.664946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:19.674395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:19.23268Z","time spent":"441.713049ms","remote":"127.0.0.1:37572","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-05T05:22:19.67457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046546839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T05:22:19.674588Z","caller":"traceutil/trace.go:171","msg":"trace[1046585334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.046610244s","start":"2026-02-05T05:22:18.627971Z","end":"2026-02-05T05:22:19.674581Z","steps":["trace[1046585334] 'agreement among raft nodes before linearized reading' (duration: 1.046549629s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:19.6746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:18.627955Z","time spent":"1.046642197s","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:22:19.675779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057018901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rhthj\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-05T05:22:19.675866Z","caller":"traceutil/trace.go:171","msg":"trace[1211921891] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rhthj; range_end:; response_count:1; response_revision:609; }","duration":"1.058877469s","start":"2026-02-05T05:22:18.616945Z","end":"2026-02-05T05:22:19.675823Z","steps":["trace[1211921891] 'agreement among raft nodes before linearized reading' (duration: 1.056914334s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:19.675911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:18.616919Z","time spent":"1.058977747s","remote":"127.0.0.1:37754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-rhthj\" "} {"level":"info","ts":"2026-02-05T05:23:36.21005Z","caller":"traceutil/trace.go:171","msg":"trace[1117799538] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"138.154841ms","start":"2026-02-05T05:23:36.071858Z","end":"2026-02-05T05:23:36.210013Z","steps":["trace[1117799538] 'read index received' (duration: 93.61448ms)","trace[1117799538] 'applied index is now lower than readState.Index' (duration: 44.538531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:36.211078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.17927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-02-05T05:23:36.211241Z","caller":"traceutil/trace.go:171","msg":"trace[133089849] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:954; }","duration":"139.325991ms","start":"2026-02-05T05:23:36.071826Z","end":"2026-02-05T05:23:36.211152Z","steps":["trace[133089849] 'agreement among raft nodes before linearized reading' (duration: 138.318042ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:23:36.211811Z","caller":"traceutil/trace.go:171","msg":"trace[317383941] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"140.212847ms","start":"2026-02-05T05:23:36.071583Z","end":"2026-02-05T05:23:36.211796Z","steps":["trace[317383941] 'process raft request' (duration: 93.877932ms)","trace[317383941] 'compare' (duration: 44.043068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:36.44324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.588331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:23:36.443666Z","caller":"traceutil/trace.go:171","msg":"trace[1471085876] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"131.23336ms","start":"2026-02-05T05:23:36.31228Z","end":"2026-02-05T05:23:36.443513Z","steps":["trace[1471085876] 'read index received' (duration: 6.143946ms)","trace[1471085876] 'applied index is now lower than readState.Index' (duration: 125.087814ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:23:36.443871Z","caller":"traceutil/trace.go:171","msg":"trace[1580327752] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"146.668065ms","start":"2026-02-05T05:23:36.297189Z","end":"2026-02-05T05:23:36.443857Z","steps":["trace[1580327752] 'process raft request' (duration: 21.258838ms)","trace[1580327752] 'compare' (duration: 124.507091ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:36.44426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.970766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:23:36.444448Z","caller":"traceutil/trace.go:171","msg":"trace[635242612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:958; }","duration":"132.185637ms","start":"2026-02-05T05:23:36.312249Z","end":"2026-02-05T05:23:36.444435Z","steps":["trace[635242612] 'agreement among raft nodes before linearized reading' (duration: 131.923675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:36.444801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.056203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-05T05:23:36.444856Z","caller":"traceutil/trace.go:171","msg":"trace[1181429607] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:958; }","duration":"121.136204ms","start":"2026-02-05T05:23:36.323708Z","end":"2026-02-05T05:23:36.444844Z","steps":["trace[1181429607] 'agreement among raft nodes before linearized reading' (duration: 120.979983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:38.80552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.715955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:23:38.805916Z","caller":"traceutil/trace.go:171","msg":"trace[1464995165] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"237.501887ms","start":"2026-02-05T05:23:38.568397Z","end":"2026-02-05T05:23:38.805899Z","steps":["trace[1464995165] 'read index received' (duration: 35.339418ms)","trace[1464995165] 'applied index is now lower than readState.Index' (duration: 202.161869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:38.806513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.103152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:23:38.806617Z","caller":"traceutil/trace.go:171","msg":"trace[1246396480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"238.230103ms","start":"2026-02-05T05:23:38.568371Z","end":"2026-02-05T05:23:38.806601Z","steps":["trace[1246396480] 'agreement among raft nodes before linearized reading' (duration: 237.781429ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:23:38.806862Z","caller":"traceutil/trace.go:171","msg":"trace[1494603513] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"323.001607ms","start":"2026-02-05T05:23:38.483849Z","end":"2026-02-05T05:23:38.806851Z","steps":["trace[1494603513] 'process raft request' (duration: 321.986888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:38.80698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:38.483842Z","time spent":"323.099137ms","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:23:38.806886Z","caller":"traceutil/trace.go:171","msg":"trace[1588879651] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"324.092206ms","start":"2026-02-05T05:23:38.482465Z","end":"2026-02-05T05:23:38.806557Z","steps":["trace[1588879651] 'process raft request' (duration: 121.266551ms)","trace[1588879651] 'compare' (duration: 201.554204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:38.807395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:38.482455Z","time spent":"324.859154ms","remote":"127.0.0.1:37822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}