{"level":"warn","ts":"2026-03-08T13:14:28.123054Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-08T13:14:28.123172Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-03-08T13:14:28.123282Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-08T13:14:28.123309Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-03-08T13:14:28.123344Z","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-03-08T13:14:28.124607Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-03-08T13:14:28.124808Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-03-08T13:14:28.132808Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.634992ms"} {"level":"info","ts":"2026-03-08T13:14:28.144521Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-03-08T13:14:28.144724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-03-08T13:14:28.144769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-03-08T13:14:28.144796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-08T13:14:28.144822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-03-08T13:14:28.144879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-03-08T13:14:28.1511Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-08T13:14:28.152954Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-08T13:14:28.15416Z","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-03-08T13:14:28.156314Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-08T13:14:28.157413Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-08T13:14:28.157696Z","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-03-08T13:14:28.158546Z","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-03-08T13:14:28.158598Z","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-03-08T13:14:28.159312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-03-08T13:14:28.159843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-03-08T13:14:28.161857Z","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-03-08T13:14:28.162195Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-08T13:14:28.162231Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-08T13:14:28.162038Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-03-08T13:14:28.162858Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-03-08T13:14:28.445119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-03-08T13:14:28.445176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-03-08T13:14:28.445251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-03-08T13:14:28.445279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-03-08T13:14:28.445319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-03-08T13:14:28.445344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-03-08T13:14:28.445381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-03-08T13:14:28.446232Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-08T13:14:28.446925Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-03-08T13:14:28.447041Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-08T13:14:28.447078Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-08T13:14:28.447271Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-08T13:14:28.447294Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-08T13:14:28.447536Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-03-08T13:14:28.447657Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-08T13:14:28.447722Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-08T13:14:28.45095Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-08T13:14:28.452665Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"warn","ts":"2026-03-08T13:14:37.470294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.927429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-08T13:14:37.470355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.38278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-08T13:14:37.470433Z","caller":"traceutil/trace.go:171","msg":"trace[1979818065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:338; }","duration":"128.132912ms","start":"2026-03-08T13:14:37.342281Z","end":"2026-03-08T13:14:37.470414Z","steps":["trace[1979818065] 'range keys from in-memory index tree' (duration: 127.788581ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:37.470446Z","caller":"traceutil/trace.go:171","msg":"trace[114117587] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:338; }","duration":"247.548411ms","start":"2026-03-08T13:14:37.222878Z","end":"2026-03-08T13:14:37.470426Z","steps":["trace[114117587] 'range keys from in-memory index tree' (duration: 247.179647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:37.703542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.697455ms","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-03-08T13:14:37.703637Z","caller":"traceutil/trace.go:171","msg":"trace[1450889818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:340; }","duration":"113.829343ms","start":"2026-03-08T13:14:37.589783Z","end":"2026-03-08T13:14:37.703612Z","steps":["trace[1450889818] 'range keys from in-memory index tree' (duration: 113.606429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:37.703638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.070117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-08T13:14:37.703724Z","caller":"traceutil/trace.go:171","msg":"trace[1563441166] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:340; }","duration":"132.182644ms","start":"2026-03-08T13:14:37.571525Z","end":"2026-03-08T13:14:37.703707Z","steps":["trace[1563441166] 'range keys from in-memory index tree' (duration: 131.97236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:38.129908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.233332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-08T13:14:38.130001Z","caller":"traceutil/trace.go:171","msg":"trace[1639467764] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"290.110809ms","start":"2026-03-08T13:14:37.839878Z","end":"2026-03-08T13:14:38.129989Z","steps":["trace[1639467764] 'read index received' (duration: 58.454µs)","trace[1639467764] 'applied index is now lower than readState.Index' (duration: 290.051535ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T13:14:38.130062Z","caller":"traceutil/trace.go:171","msg":"trace[56780961] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"341.324532ms","start":"2026-03-08T13:14:37.788701Z","end":"2026-03-08T13:14:38.130026Z","steps":["trace[56780961] 'process raft request' (duration: 24.62137ms)","trace[56780961] 'compare' (duration: 316.130476ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T13:14:38.130117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.238757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-08T13:14:38.13014Z","caller":"traceutil/trace.go:171","msg":"trace[529297904] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:343; }","duration":"290.319672ms","start":"2026-03-08T13:14:37.839814Z","end":"2026-03-08T13:14:38.130134Z","steps":["trace[529297904] 'agreement among raft nodes before linearized reading' (duration: 290.247178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:38.130197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:37.788688Z","time spent":"341.44988ms","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T13:14:38.130423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.628921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-08T13:14:38.130462Z","caller":"traceutil/trace.go:171","msg":"trace[792664573] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:343; }","duration":"257.739238ms","start":"2026-03-08T13:14:37.872712Z","end":"2026-03-08T13:14:38.130451Z","steps":["trace[792664573] 'agreement among raft nodes before linearized reading' (duration: 257.640852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:38.130502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.709973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-08T13:14:38.130716Z","caller":"traceutil/trace.go:171","msg":"trace[320633273] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"157.950238ms","start":"2026-03-08T13:14:37.972758Z","end":"2026-03-08T13:14:38.130709Z","steps":["trace[320633273] 'agreement among raft nodes before linearized reading' (duration: 157.716824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:38.130752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.959917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-08T13:14:38.131019Z","caller":"traceutil/trace.go:171","msg":"trace[1509203064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:343; }","duration":"108.507812ms","start":"2026-03-08T13:14:38.022495Z","end":"2026-03-08T13:14:38.131003Z","steps":["trace[1509203064] 'agreement among raft nodes before linearized reading' (duration: 107.951297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:38.130873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.21633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-08T13:14:38.131164Z","caller":"traceutil/trace.go:171","msg":"trace[599189359] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:343; }","duration":"208.514159ms","start":"2026-03-08T13:14:37.922633Z","end":"2026-03-08T13:14:38.131147Z","steps":["trace[599189359] 'agreement among raft nodes before linearized reading' (duration: 208.205969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:38.672385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827160240010273,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T13:14:39.173335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827160240010273,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T13:14:39.67424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827160240010273,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-08T13:14:39.967381Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.309840218s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-08T13:14:40.2085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.593982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-08T13:14:40.208716Z","caller":"traceutil/trace.go:171","msg":"trace[401025214] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"2.074628977s","start":"2026-03-08T13:14:38.134066Z","end":"2026-03-08T13:14:40.208695Z","steps":["trace[401025214] 'process raft request' (duration: 1.833646628s)","trace[401025214] 'compare' (duration: 239.375408ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T13:14:40.209343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.134046Z","time spent":"2.074769417s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T13:14:41.899965Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.694825005s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-08T13:14:42.015542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.200543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-08T13:14:42.015745Z","caller":"traceutil/trace.go:171","msg":"trace[2077541672] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:356; }","duration":"3.843841257s","start":"2026-03-08T13:14:38.171852Z","end":"2026-03-08T13:14:42.015693Z","steps":["trace[2077541672] 'read index received' (duration: 1.795759804s)","trace[2077541672] 'applied index is now lower than readState.Index' (duration: 2.048080393s)"],"step_count":2} {"level":"info","ts":"2026-03-08T13:14:42.015791Z","caller":"traceutil/trace.go:171","msg":"trace[760196720] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"3.879216185s","start":"2026-03-08T13:14:38.136542Z","end":"2026-03-08T13:14:42.015758Z","steps":["trace[760196720] 'process raft request' (duration: 3.763736204s)","trace[760196720] 'compare' (duration: 115.077676ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T13:14:42.015848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.843989087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-08T13:14:42.015883Z","caller":"traceutil/trace.go:171","msg":"trace[534785030] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:346; }","duration":"3.844061761s","start":"2026-03-08T13:14:38.171809Z","end":"2026-03-08T13:14:42.015871Z","steps":["trace[534785030] 'agreement among raft nodes before linearized reading' (duration: 3.843992027s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.015882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.136528Z","time spent":"3.879318132s","remote":"127.0.0.1:36942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T13:14:42.015922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.171791Z","time spent":"3.844122475s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-03-08T13:14:42.015979Z","caller":"traceutil/trace.go:171","msg":"trace[970729239] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"3.879270409s","start":"2026-03-08T13:14:38.136591Z","end":"2026-03-08T13:14:42.015861Z","steps":["trace[970729239] 'process raft request' (duration: 3.879029773s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.01641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.13657Z","time spent":"3.879503473s","remote":"127.0.0.1:36942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T13:14:42.018116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.841447701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-08T13:14:42.018181Z","caller":"traceutil/trace.go:171","msg":"trace[708639515] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:348; }","duration":"3.841541956s","start":"2026-03-08T13:14:38.176625Z","end":"2026-03-08T13:14:42.018167Z","steps":["trace[708639515] 'agreement among raft nodes before linearized reading' (duration: 3.841423228s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.018219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.17661Z","time spent":"3.84160191s","remote":"127.0.0.1:37280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-03-08T13:14:42.018806Z","caller":"traceutil/trace.go:171","msg":"trace[1517962583] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"1.962451064s","start":"2026-03-08T13:14:40.056333Z","end":"2026-03-08T13:14:42.018784Z","steps":["trace[1517962583] 'process raft request' (duration: 1.96159798s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.018925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:40.056311Z","time spent":"1.96254777s","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T13:14:42.019141Z","caller":"traceutil/trace.go:171","msg":"trace[69304641] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"1.803159828s","start":"2026-03-08T13:14:40.215968Z","end":"2026-03-08T13:14:42.019128Z","steps":["trace[69304641] 'process raft request' (duration: 1.802032356s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.0192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:40.215948Z","time spent":"1.803224002s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-08T13:14:42.019341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.505295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-08T13:14:42.019379Z","caller":"traceutil/trace.go:171","msg":"trace[1866106895] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:348; }","duration":"397.541847ms","start":"2026-03-08T13:14:41.621826Z","end":"2026-03-08T13:14:42.019368Z","steps":["trace[1866106895] 'agreement among raft nodes before linearized reading' (duration: 397.481813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.019416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:41.62176Z","time spent":"397.647274ms","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-03-08T13:14:42.019633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.205233347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-08T13:14:42.019634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.645634021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-08T13:14:42.019691Z","caller":"traceutil/trace.go:171","msg":"trace[733380875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"3.205367386s","start":"2026-03-08T13:14:38.814309Z","end":"2026-03-08T13:14:42.019677Z","steps":["trace[733380875] 'agreement among raft nodes before linearized reading' (duration: 3.205235397s)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.019712Z","caller":"traceutil/trace.go:171","msg":"trace[1497624774] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"3.64578747s","start":"2026-03-08T13:14:38.373905Z","end":"2026-03-08T13:14:42.019693Z","steps":["trace[1497624774] 'agreement among raft nodes before linearized reading' (duration: 3.645596418s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.019726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.814285Z","time spent":"3.20543327s","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-08T13:14:42.01976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.373883Z","time spent":"3.645868295s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-03-08T13:14:42.019971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.496317434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-08T13:14:42.020011Z","caller":"traceutil/trace.go:171","msg":"trace[1605676506] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"3.496412099s","start":"2026-03-08T13:14:38.523589Z","end":"2026-03-08T13:14:42.020001Z","steps":["trace[1605676506] 'agreement among raft nodes before linearized reading' (duration: 3.496350995s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.020044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.52356Z","time spent":"3.496478094s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-03-08T13:14:42.020165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.569979291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-03-08T13:14:42.02021Z","caller":"traceutil/trace.go:171","msg":"trace[1184131931] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:348; }","duration":"3.570093148s","start":"2026-03-08T13:14:38.450104Z","end":"2026-03-08T13:14:42.020197Z","steps":["trace[1184131931] 'agreement among raft nodes before linearized reading' (duration: 3.56997702s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.020213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.285757855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-08T13:14:42.020269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.45004Z","time spent":"3.570221086s","remote":"127.0.0.1:37040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-03-08T13:14:42.020277Z","caller":"traceutil/trace.go:171","msg":"trace[1655685778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:348; }","duration":"2.285880123s","start":"2026-03-08T13:14:39.734386Z","end":"2026-03-08T13:14:42.020266Z","steps":["trace[1655685778] 'agreement among raft nodes before linearized reading' (duration: 2.285770056s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.020308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:39.734355Z","time spent":"2.285945987s","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-08T13:14:42.020337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.696517806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-08T13:14:42.020443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.540511692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-08T13:14:42.020475Z","caller":"traceutil/trace.go:171","msg":"trace[1351609138] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"3.540594317s","start":"2026-03-08T13:14:38.479868Z","end":"2026-03-08T13:14:42.020462Z","steps":["trace[1351609138] 'agreement among raft nodes before linearized reading' (duration: 3.540539533s)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.020489Z","caller":"traceutil/trace.go:171","msg":"trace[1994971129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:348; }","duration":"3.697074022s","start":"2026-03-08T13:14:38.32335Z","end":"2026-03-08T13:14:42.020424Z","steps":["trace[1994971129] 'agreement among raft nodes before linearized reading' (duration: 3.696264611s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.020669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.505407184s","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-03-08T13:14:42.02071Z","caller":"traceutil/trace.go:171","msg":"trace[922297251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:348; }","duration":"2.505477388s","start":"2026-03-08T13:14:39.51522Z","end":"2026-03-08T13:14:42.020697Z","steps":["trace[922297251] 'agreement among raft nodes before linearized reading' (duration: 2.505406124s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.020738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:39.5152Z","time spent":"2.505531982s","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-08T13:14:42.020752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.059998994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-08T13:14:42.020806Z","caller":"traceutil/trace.go:171","msg":"trace[742991776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:348; }","duration":"3.060110501s","start":"2026-03-08T13:14:38.960682Z","end":"2026-03-08T13:14:42.020792Z","steps":["trace[742991776] 'agreement among raft nodes before linearized reading' (duration: 3.060005275s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.020837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.960656Z","time spent":"3.060173955s","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-08T13:14:42.020843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.798052394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-08T13:14:42.020882Z","caller":"traceutil/trace.go:171","msg":"trace[1770900237] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:348; }","duration":"3.798089308s","start":"2026-03-08T13:14:38.222784Z","end":"2026-03-08T13:14:42.020873Z","steps":["trace[1770900237] 'agreement among raft nodes before linearized reading' (duration: 3.798026364s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.02091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.222741Z","time spent":"3.798162202s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-03-08T13:14:42.02093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.747787217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-08T13:14:42.02103Z","caller":"traceutil/trace.go:171","msg":"trace[943527952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:348; }","duration":"3.747915266s","start":"2026-03-08T13:14:38.273088Z","end":"2026-03-08T13:14:42.021004Z","steps":["trace[943527952] 'agreement among raft nodes before linearized reading' (duration: 3.747735365s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.021078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.273075Z","time spent":"3.747993871s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-03-08T13:14:42.020685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.323341Z","time spent":"3.697281515s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-08T13:14:42.020505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.479838Z","time spent":"3.540661301s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-03-08T13:14:42.020173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.594839812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-08T13:14:42.021208Z","caller":"traceutil/trace.go:171","msg":"trace[890042113] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"3.595905249s","start":"2026-03-08T13:14:38.425293Z","end":"2026-03-08T13:14:42.021198Z","steps":["trace[890042113] 'agreement among raft nodes before linearized reading' (duration: 3.594850652s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.021238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:14:38.425275Z","time spent":"3.595955102s","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-03-08T13:14:42.238886Z","caller":"traceutil/trace.go:171","msg":"trace[986895800] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"218.255847ms","start":"2026-03-08T13:14:42.0206Z","end":"2026-03-08T13:14:42.238856Z","steps":["trace[986895800] 'process raft request' (duration: 211.162427ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.240153Z","caller":"traceutil/trace.go:171","msg":"trace[1237184539] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"210.42783ms","start":"2026-03-08T13:14:42.029702Z","end":"2026-03-08T13:14:42.24013Z","steps":["trace[1237184539] 'process raft request' (duration: 209.737616ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.240221Z","caller":"traceutil/trace.go:171","msg":"trace[1501305806] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"209.676402ms","start":"2026-03-08T13:14:42.030328Z","end":"2026-03-08T13:14:42.240005Z","steps":["trace[1501305806] 'process raft request' (duration: 209.399324ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.240389Z","caller":"traceutil/trace.go:171","msg":"trace[2089014749] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"210.335504ms","start":"2026-03-08T13:14:42.030032Z","end":"2026-03-08T13:14:42.240368Z","steps":["trace[2089014749] 'process raft request' (duration: 209.63893ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.240827Z","caller":"traceutil/trace.go:171","msg":"trace[1760317762] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"208.527939ms","start":"2026-03-08T13:14:42.031997Z","end":"2026-03-08T13:14:42.240525Z","steps":["trace[1760317762] 'process raft request' (duration: 207.923021ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.24085Z","caller":"traceutil/trace.go:171","msg":"trace[176675343] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"208.497948ms","start":"2026-03-08T13:14:42.032328Z","end":"2026-03-08T13:14:42.240826Z","steps":["trace[176675343] 'process raft request' (duration: 207.722959ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.240132Z","caller":"traceutil/trace.go:171","msg":"trace[1818981248] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"207.993946ms","start":"2026-03-08T13:14:42.032098Z","end":"2026-03-08T13:14:42.240092Z","steps":["trace[1818981248] 'process raft request' (duration: 207.91316ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.24104Z","caller":"traceutil/trace.go:171","msg":"trace[1318971390] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"208.581313ms","start":"2026-03-08T13:14:42.032063Z","end":"2026-03-08T13:14:42.240644Z","steps":["trace[1318971390] 'process raft request' (duration: 207.894989ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.241352Z","caller":"traceutil/trace.go:171","msg":"trace[1221759960] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"208.744814ms","start":"2026-03-08T13:14:42.031929Z","end":"2026-03-08T13:14:42.240674Z","steps":["trace[1221759960] 'process raft request' (duration: 207.949773ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.241481Z","caller":"traceutil/trace.go:171","msg":"trace[519006234] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"210.142112ms","start":"2026-03-08T13:14:42.030541Z","end":"2026-03-08T13:14:42.240683Z","steps":["trace[519006234] 'process raft request' (duration: 209.284238ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.2415Z","caller":"traceutil/trace.go:171","msg":"trace[2021001402] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"208.752344ms","start":"2026-03-08T13:14:42.032732Z","end":"2026-03-08T13:14:42.241485Z","steps":["trace[2021001402] 'process raft request' (duration: 208.011607ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.241541Z","caller":"traceutil/trace.go:171","msg":"trace[58353161] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"208.674489ms","start":"2026-03-08T13:14:42.032852Z","end":"2026-03-08T13:14:42.241526Z","steps":["trace[58353161] 'process raft request' (duration: 208.190268ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.241792Z","caller":"traceutil/trace.go:171","msg":"trace[1577783314] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"208.906664ms","start":"2026-03-08T13:14:42.032877Z","end":"2026-03-08T13:14:42.241783Z","steps":["trace[1577783314] 'process raft request' (duration: 208.442885ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.241953Z","caller":"traceutil/trace.go:171","msg":"trace[1121915066] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"208.634457ms","start":"2026-03-08T13:14:42.033306Z","end":"2026-03-08T13:14:42.24194Z","steps":["trace[1121915066] 'process raft request' (duration: 208.242202ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.241851Z","caller":"traceutil/trace.go:171","msg":"trace[1644624481] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:361; }","duration":"215.654641ms","start":"2026-03-08T13:14:42.026183Z","end":"2026-03-08T13:14:42.241837Z","steps":["trace[1644624481] 'read index received' (duration: 205.667138ms)","trace[1644624481] 'applied index is now lower than readState.Index' (duration: 9.986353ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T13:14:42.24228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.071049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-08T13:14:42.242464Z","caller":"traceutil/trace.go:171","msg":"trace[1586912947] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:363; }","duration":"216.293902ms","start":"2026-03-08T13:14:42.026153Z","end":"2026-03-08T13:14:42.242447Z","steps":["trace[1586912947] 'agreement among raft nodes before linearized reading' (duration: 216.030675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:14:42.242791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.512967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-08T13:14:42.242826Z","caller":"traceutil/trace.go:171","msg":"trace[1062404821] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:364; }","duration":"216.594832ms","start":"2026-03-08T13:14:42.026223Z","end":"2026-03-08T13:14:42.242818Z","steps":["trace[1062404821] 'agreement among raft nodes before linearized reading' (duration: 216.500166ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.240453Z","caller":"traceutil/trace.go:171","msg":"trace[272384689] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"210.010934ms","start":"2026-03-08T13:14:42.030415Z","end":"2026-03-08T13:14:42.240425Z","steps":["trace[272384689] 'process raft request' (duration: 209.364923ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.474066Z","caller":"traceutil/trace.go:171","msg":"trace[1712958784] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"159.600166ms","start":"2026-03-08T13:14:42.314431Z","end":"2026-03-08T13:14:42.474031Z","steps":["trace[1712958784] 'process raft request' (duration: 61.735607ms)","trace[1712958784] 'compare' (duration: 97.542508ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T13:14:42.474293Z","caller":"traceutil/trace.go:171","msg":"trace[829640006] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"159.710422ms","start":"2026-03-08T13:14:42.314539Z","end":"2026-03-08T13:14:42.474249Z","steps":["trace[829640006] 'process raft request' (duration: 159.422284ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.480304Z","caller":"traceutil/trace.go:171","msg":"trace[1028762139] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"143.444501ms","start":"2026-03-08T13:14:42.336835Z","end":"2026-03-08T13:14:42.48028Z","steps":["trace[1028762139] 'process raft request' (duration: 143.226927ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.480343Z","caller":"traceutil/trace.go:171","msg":"trace[2137012272] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"140.162283ms","start":"2026-03-08T13:14:42.340144Z","end":"2026-03-08T13:14:42.480306Z","steps":["trace[2137012272] 'process raft request' (duration: 140.032084ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.664845Z","caller":"traceutil/trace.go:171","msg":"trace[1131387173] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"112.633986ms","start":"2026-03-08T13:14:42.55219Z","end":"2026-03-08T13:14:42.664824Z","steps":["trace[1131387173] 'process raft request' (duration: 70.460561ms)","trace[1131387173] 'compare' (duration: 41.926259ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T13:14:42.665188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.274183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-03-08T13:14:42.665293Z","caller":"traceutil/trace.go:171","msg":"trace[697901612] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:392; }","duration":"112.461135ms","start":"2026-03-08T13:14:42.552806Z","end":"2026-03-08T13:14:42.665267Z","steps":["trace[697901612] 'agreement among raft nodes before linearized reading' (duration: 112.22992ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:14:42.664774Z","caller":"traceutil/trace.go:171","msg":"trace[1434899958] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"111.900589ms","start":"2026-03-08T13:14:42.552839Z","end":"2026-03-08T13:14:42.664739Z","steps":["trace[1434899958] 'read index received' (duration: 69.83142ms)","trace[1434899958] 'applied index is now lower than readState.Index' (duration: 42.067949ms)"],"step_count":2} {"level":"warn","ts":"2026-03-08T13:15:10.900724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827160240010667,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-08T13:15:11.05748Z","caller":"traceutil/trace.go:171","msg":"trace[1982395992] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"658.610265ms","start":"2026-03-08T13:15:10.398849Z","end":"2026-03-08T13:15:11.057459Z","steps":["trace[1982395992] 'process raft request' (duration: 658.465165ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:15:11.05763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:15:10.398835Z","time spent":"658.712622ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-08T13:15:11.064013Z","caller":"traceutil/trace.go:171","msg":"trace[2029070387] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"664.176013ms","start":"2026-03-08T13:15:10.399811Z","end":"2026-03-08T13:15:11.063987Z","steps":["trace[2029070387] 'read index received' (duration: 657.563673ms)","trace[2029070387] 'applied index is now lower than readState.Index' (duration: 6.61056ms)"],"step_count":2} {"level":"info","ts":"2026-03-08T13:15:11.064091Z","caller":"traceutil/trace.go:171","msg":"trace[620011835] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"662.272014ms","start":"2026-03-08T13:15:10.401791Z","end":"2026-03-08T13:15:11.064063Z","steps":["trace[620011835] 'process raft request' (duration: 662.074211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:15:11.064339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.76522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-08T13:15:11.064422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.603013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-08T13:15:11.064441Z","caller":"traceutil/trace.go:171","msg":"trace[739603072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"318.91005ms","start":"2026-03-08T13:15:10.745515Z","end":"2026-03-08T13:15:11.064425Z","steps":["trace[739603072] 'agreement among raft nodes before linearized reading' (duration: 318.76204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:15:11.064449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.597332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-08T13:15:11.064472Z","caller":"traceutil/trace.go:171","msg":"trace[38264261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"664.668927ms","start":"2026-03-08T13:15:10.399789Z","end":"2026-03-08T13:15:11.064458Z","steps":["trace[38264261] 'agreement among raft nodes before linearized reading' (duration: 664.517607ms)"],"step_count":1} {"level":"info","ts":"2026-03-08T13:15:11.064501Z","caller":"traceutil/trace.go:171","msg":"trace[1431953139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"664.70475ms","start":"2026-03-08T13:15:10.39978Z","end":"2026-03-08T13:15:11.064485Z","steps":["trace[1431953139] 'agreement among raft nodes before linearized reading' (duration: 664.344225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:15:11.064538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:15:10.39978Z","time spent":"664.750333ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-08T13:15:11.064549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:15:10.399771Z","time spent":"664.766863ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-08T13:15:11.064481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:15:10.745498Z","time spent":"318.974114ms","remote":"127.0.0.1:36854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-08T13:15:11.064397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:15:10.401774Z","time spent":"662.372741ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T13:15:12.731557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10853167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-08T13:15:12.731624Z","caller":"traceutil/trace.go:171","msg":"trace[1692391426] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:558; }","duration":"1.108634878s","start":"2026-03-08T13:15:11.622971Z","end":"2026-03-08T13:15:12.731606Z","steps":["trace[1692391426] 'range keys from in-memory index tree' (duration: 1.108451666s)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:15:12.731649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:15:11.622955Z","time spent":"1.108688741s","remote":"127.0.0.1:36872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"info","ts":"2026-03-08T13:15:12.741181Z","caller":"traceutil/trace.go:171","msg":"trace[245121333] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"434.748596ms","start":"2026-03-08T13:15:12.306404Z","end":"2026-03-08T13:15:12.741153Z","steps":["trace[245121333] 'process raft request' (duration: 434.574784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-08T13:15:12.741399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-08T13:15:12.30638Z","time spent":"434.909457ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-08T13:15:14.067006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.694871ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-08T13:15:14.067152Z","caller":"traceutil/trace.go:171","msg":"trace[1915789674] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:568; }","duration":"147.889164ms","start":"2026-03-08T13:15:13.919242Z","end":"2026-03-08T13:15:14.067131Z","steps":["trace[1915789674] 'range keys from in-memory index tree' (duration: 147.67422ms)"],"step_count":1}