{"level":"warn","ts":"2026-02-06T00:41:29.718634Z","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-06T00:41:29.718807Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-06T00:41:29.718915Z","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-06T00:41:29.718945Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-02-06T00:41:29.718995Z","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-06T00:41:29.720052Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-02-06T00:41:29.720325Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-06T00:41:29.727133Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.176603ms"} {"level":"info","ts":"2026-02-06T00:41:29.748275Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-02-06T00:41:29.748366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T00:41:29.748402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-02-06T00:41:29.748427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T00:41:29.748447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-02-06T00:41:29.748497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-02-06T00:41:29.757647Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T00:41:29.759699Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T00:41:29.761992Z","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-06T00:41:29.767779Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T00:41:29.768183Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T00:41:29.768356Z","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-06T00:41:29.768424Z","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-06T00:41:29.768452Z","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-06T00:41:29.768776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-02-06T00:41:29.768901Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-02-06T00:41:29.774894Z","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-06T00:41:29.775357Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T00:41:29.775231Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-02-06T00:41:29.776396Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-02-06T00:41:29.776327Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T00:41:29.851544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T00:41:29.851588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T00:41:29.851684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-02-06T00:41:29.851739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-02-06T00:41:29.851751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-02-06T00:41:29.851814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-02-06T00:41:29.851861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-02-06T00:41:29.855661Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:41:29.857945Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T00:41:29.858018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:41:29.85829Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T00:41:29.858334Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T00:41:29.858385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T00:41:29.860241Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-02-06T00:41:29.86056Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:41:29.862061Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:41:29.862163Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T00:41:29.882746Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T00:41:41.002796Z","caller":"traceutil/trace.go:171","msg":"trace[888030987] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"130.046835ms","start":"2026-02-06T00:41:40.872671Z","end":"2026-02-06T00:41:41.002718Z","steps":["trace[888030987] 'read index received' (duration: 129.922334ms)","trace[888030987] 'applied index is now lower than readState.Index' (duration: 123.371µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:41.002959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.285376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4116"} {"level":"info","ts":"2026-02-06T00:41:41.003013Z","caller":"traceutil/trace.go:171","msg":"trace[688659350] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:395; }","duration":"130.386867ms","start":"2026-02-06T00:41:40.872615Z","end":"2026-02-06T00:41:41.003001Z","steps":["trace[688659350] 'agreement among raft nodes before linearized reading' (duration: 130.274666ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:41.003218Z","caller":"traceutil/trace.go:171","msg":"trace[720816806] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"165.440487ms","start":"2026-02-06T00:41:40.837751Z","end":"2026-02-06T00:41:41.003192Z","steps":["trace[720816806] 'process raft request' (duration: 164.798653ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:41:46.594464Z","caller":"traceutil/trace.go:171","msg":"trace[196757766] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"217.131606ms","start":"2026-02-06T00:41:46.377308Z","end":"2026-02-06T00:41:46.594439Z","steps":["trace[196757766] 'read index received' (duration: 216.897594ms)","trace[196757766] 'applied index is now lower than readState.Index' (duration: 232.312µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:41:46.594655Z","caller":"traceutil/trace.go:171","msg":"trace[1616172705] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"218.441964ms","start":"2026-02-06T00:41:46.376184Z","end":"2026-02-06T00:41:46.594626Z","steps":["trace[1616172705] 'process raft request' (duration: 218.039092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:46.594712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.379918ms","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-06T00:41:46.594777Z","caller":"traceutil/trace.go:171","msg":"trace[712771820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"217.478318ms","start":"2026-02-06T00:41:46.377284Z","end":"2026-02-06T00:41:46.594763Z","steps":["trace[712771820] 'agreement among raft nodes before linearized reading' (duration: 217.340847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:46.595071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.416622ms","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-06T00:41:46.595123Z","caller":"traceutil/trace.go:171","msg":"trace[1524930539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"215.489463ms","start":"2026-02-06T00:41:46.379619Z","end":"2026-02-06T00:41:46.595108Z","steps":["trace[1524930539] 'agreement among raft nodes before linearized reading' (duration: 215.375392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:47.186881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.920498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T00:41:47.187231Z","caller":"traceutil/trace.go:171","msg":"trace[2090872693] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"585.131445ms","start":"2026-02-06T00:41:46.602055Z","end":"2026-02-06T00:41:47.187186Z","steps":["trace[2090872693] 'process raft request' (duration: 433.23099ms)","trace[2090872693] 'compare' (duration: 150.470055ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:47.187431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:46.602038Z","time spent":"585.323327ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:41:47.212226Z","caller":"traceutil/trace.go:171","msg":"trace[1536641856] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"608.945281ms","start":"2026-02-06T00:41:46.603267Z","end":"2026-02-06T00:41:47.212213Z","steps":["trace[1536641856] 'process raft request' (duration: 608.68452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:47.212477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:46.60326Z","time spent":"609.169163ms","remote":"127.0.0.1:38270","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-06T00:41:47.213159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.813048ms","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-06T00:41:47.213555Z","caller":"traceutil/trace.go:171","msg":"trace[455402559] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:419; }","duration":"307.238372ms","start":"2026-02-06T00:41:46.906303Z","end":"2026-02-06T00:41:47.213541Z","steps":["trace[455402559] 'agreement among raft nodes before linearized reading' (duration: 306.805949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:41:47.213795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:41:46.906287Z","time spent":"307.469973ms","remote":"127.0.0.1:38446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4056,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-06T00:41:47.212055Z","caller":"traceutil/trace.go:171","msg":"trace[1781277204] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:430; }","duration":"305.695129ms","start":"2026-02-06T00:41:46.906344Z","end":"2026-02-06T00:41:47.212039Z","steps":["trace[1781277204] 'read index received' (duration: 128.956661ms)","trace[1781277204] 'applied index is now lower than readState.Index' (duration: 176.737158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T00:41:47.215158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.957274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:41:47.215363Z","caller":"traceutil/trace.go:171","msg":"trace[701212261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"251.166026ms","start":"2026-02-06T00:41:46.964152Z","end":"2026-02-06T00:41:47.215318Z","steps":["trace[701212261] 'agreement among raft nodes before linearized reading' (duration: 250.516892ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:01.41187Z","caller":"traceutil/trace.go:171","msg":"trace[402840822] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"148.491201ms","start":"2026-02-06T00:42:01.263364Z","end":"2026-02-06T00:42:01.411855Z","steps":["trace[402840822] 'read index received' (duration: 148.38076ms)","trace[402840822] 'applied index is now lower than readState.Index' (duration: 109.661µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:01.411964Z","caller":"traceutil/trace.go:171","msg":"trace[393083119] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"154.35184ms","start":"2026-02-06T00:42:01.257605Z","end":"2026-02-06T00:42:01.411957Z","steps":["trace[393083119] 'process raft request' (duration: 154.143649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:01.412139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.762262ms","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-06T00:42:01.412165Z","caller":"traceutil/trace.go:171","msg":"trace[1263848929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"148.818913ms","start":"2026-02-06T00:42:01.263338Z","end":"2026-02-06T00:42:01.412157Z","steps":["trace[1263848929] 'agreement among raft nodes before linearized reading' (duration: 148.764462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:01.412256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.634558ms","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-06T00:42:01.412272Z","caller":"traceutil/trace.go:171","msg":"trace[1680792410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"111.673649ms","start":"2026-02-06T00:42:01.300594Z","end":"2026-02-06T00:42:01.412267Z","steps":["trace[1680792410] 'agreement among raft nodes before linearized reading' (duration: 111.642739ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:01.807522Z","caller":"traceutil/trace.go:171","msg":"trace[1399929474] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"391.320361ms","start":"2026-02-06T00:42:01.416161Z","end":"2026-02-06T00:42:01.807481Z","steps":["trace[1399929474] 'process raft request' (duration: 391.15375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:01.807947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:01.416129Z","time spent":"391.641524ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:01.822724Z","caller":"traceutil/trace.go:171","msg":"trace[1422549971] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"405.971691ms","start":"2026-02-06T00:42:01.416739Z","end":"2026-02-06T00:42:01.822711Z","steps":["trace[1422549971] 'process raft request' (duration: 405.834471ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:01.822713Z","caller":"traceutil/trace.go:171","msg":"trace[990634517] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"402.486918ms","start":"2026-02-06T00:42:01.420211Z","end":"2026-02-06T00:42:01.822698Z","steps":["trace[990634517] 'process raft request' (duration: 402.447628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:01.823853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:01.420195Z","time spent":"403.588525ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:42:01.826312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:01.416729Z","time spent":"406.098212ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T00:42:02.845024Z","caller":"traceutil/trace.go:171","msg":"trace[671128220] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"123.236965ms","start":"2026-02-06T00:42:02.721759Z","end":"2026-02-06T00:42:02.844995Z","steps":["trace[671128220] 'process raft request' (duration: 123.125134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:08.339016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831408231938366110,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T00:42:08.467111Z","caller":"traceutil/trace.go:171","msg":"trace[1655919755] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"628.127985ms","start":"2026-02-06T00:42:07.838964Z","end":"2026-02-06T00:42:08.467092Z","steps":["trace[1655919755] 'read index received' (duration: 627.991514ms)","trace[1655919755] 'applied index is now lower than readState.Index' (duration: 135.701µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:08.46719Z","caller":"traceutil/trace.go:171","msg":"trace[1262286703] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"628.334536ms","start":"2026-02-06T00:42:07.83885Z","end":"2026-02-06T00:42:08.467184Z","steps":["trace[1262286703] 'process raft request' (duration: 628.146475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:08.467265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:07.838842Z","time spent":"628.367296ms","remote":"127.0.0.1:38270","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-06T00:42:08.467387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.378514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:08.467455Z","caller":"traceutil/trace.go:171","msg":"trace[1260486169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"504.467995ms","start":"2026-02-06T00:42:07.962967Z","end":"2026-02-06T00:42:08.467435Z","steps":["trace[1260486169] 'agreement among raft nodes before linearized reading' (duration: 504.364334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:08.467497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:07.962938Z","time spent":"504.547025ms","remote":"127.0.0.1:38044","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-06T00:42:08.467569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.599727ms","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-06T00:42:08.467617Z","caller":"traceutil/trace.go:171","msg":"trace[468071457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"628.633478ms","start":"2026-02-06T00:42:07.838954Z","end":"2026-02-06T00:42:08.467588Z","steps":["trace[468071457] 'agreement among raft nodes before linearized reading' (duration: 628.541467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:08.46764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:07.83895Z","time spent":"628.685508ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T00:42:08.694345Z","caller":"traceutil/trace.go:171","msg":"trace[1270864039] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"221.691385ms","start":"2026-02-06T00:42:08.472637Z","end":"2026-02-06T00:42:08.694328Z","steps":["trace[1270864039] 'process raft request' (duration: 219.551591ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:10.042312Z","caller":"traceutil/trace.go:171","msg":"trace[1795645839] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"144.590517ms","start":"2026-02-06T00:42:09.897705Z","end":"2026-02-06T00:42:10.042295Z","steps":["trace[1795645839] 'read index received' (duration: 144.447933ms)","trace[1795645839] 'applied index is now lower than readState.Index' (duration: 141.894µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T00:42:10.042531Z","caller":"traceutil/trace.go:171","msg":"trace[1986355937] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"556.432913ms","start":"2026-02-06T00:42:09.486091Z","end":"2026-02-06T00:42:10.042524Z","steps":["trace[1986355937] 'process raft request' (duration: 556.108793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:10.042603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:09.486074Z","time spent":"556.477874ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T00:42:10.042766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.060051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qzwrc\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-06T00:42:10.042787Z","caller":"traceutil/trace.go:171","msg":"trace[1974952846] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qzwrc; range_end:; response_count:1; response_revision:559; }","duration":"145.105422ms","start":"2026-02-06T00:42:09.897675Z","end":"2026-02-06T00:42:10.04278Z","steps":["trace[1974952846] 'agreement among raft nodes before linearized reading' (duration: 145.027159ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T00:42:10.250738Z","caller":"traceutil/trace.go:171","msg":"trace[1978305342] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"194.627746ms","start":"2026-02-06T00:42:10.056092Z","end":"2026-02-06T00:42:10.25072Z","steps":["trace[1978305342] 'process raft request' (duration: 194.500852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:12.312784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:11.886056Z","time spent":"426.722262ms","remote":"127.0.0.1:56374","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T00:42:12.313145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839220061s","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-06T00:42:12.313174Z","caller":"traceutil/trace.go:171","msg":"trace[83911595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"1.839255182s","start":"2026-02-06T00:42:10.473906Z","end":"2026-02-06T00:42:12.313162Z","steps":["trace[83911595] 'range keys from in-memory index tree' (duration: 1.839072276s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:12.313168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.346249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-06T00:42:12.313196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:10.473849Z","time spent":"1.839343335s","remote":"127.0.0.1:38270","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":"info","ts":"2026-02-06T00:42:12.313217Z","caller":"traceutil/trace.go:171","msg":"trace[1954789546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"266.424132ms","start":"2026-02-06T00:42:12.046781Z","end":"2026-02-06T00:42:12.313205Z","steps":["trace[1954789546] 'range keys from in-memory index tree' (duration: 266.220806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:12.313357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.615988134s","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-06T00:42:12.31338Z","caller":"traceutil/trace.go:171","msg":"trace[491194088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"1.616031406s","start":"2026-02-06T00:42:10.69734Z","end":"2026-02-06T00:42:12.313372Z","steps":["trace[491194088] 'range keys from in-memory index tree' (duration: 1.615899782s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:12.313396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:10.697325Z","time spent":"1.616067346s","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-06T00:42:12.313497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403763247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-06T00:42:12.313522Z","caller":"traceutil/trace.go:171","msg":"trace[931791636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"1.403817348s","start":"2026-02-06T00:42:10.909699Z","end":"2026-02-06T00:42:12.313517Z","steps":["trace[931791636] 'range keys from in-memory index tree' (duration: 1.403697165s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:12.313538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:10.909684Z","time spent":"1.403849639s","remote":"127.0.0.1:38270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-06T00:42:12.313581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350975783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T00:42:12.313592Z","caller":"traceutil/trace.go:171","msg":"trace[76216278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.351009504s","start":"2026-02-06T00:42:10.962578Z","end":"2026-02-06T00:42:12.313587Z","steps":["trace[76216278] 'range keys from in-memory index tree' (duration: 1.350936702s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T00:42:12.313604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:42:10.962564Z","time spent":"1.351037384s","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}