{"level":"warn","ts":"2026-07-15T19:49:15.23198Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T19:49:15.232154Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-15T19:49:15.232234Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T19:49:15.232243Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-07-15T19:49:15.232268Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T19:49:15.232922Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-07-15T19:49:15.232992Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-15T19:49:15.23646Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.27492ms"} {"level":"info","ts":"2026-07-15T19:49:15.248337Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-07-15T19:49:15.248436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T19:49:15.248462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-07-15T19:49:15.248483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T19:49:15.2485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-07-15T19:49:15.248576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-07-15T19:49:15.256356Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T19:49:15.257555Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T19:49:15.258848Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-15T19:49:15.261361Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T19:49:15.26215Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T19:49:15.262404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-07-15T19:49:15.262493Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-07-15T19:49:15.262553Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T19:49:15.262573Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T19:49:15.262579Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T19:49:15.268453Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T19:49:15.268669Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T19:49:15.268695Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T19:49:15.269227Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-07-15T19:49:15.269261Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-07-15T19:49:16.24936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T19:49:16.249417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T19:49:16.249457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-07-15T19:49:16.249476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-07-15T19:49:16.249614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-07-15T19:49:16.249693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-07-15T19:49:16.249717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-07-15T19:49:16.25097Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T19:49:16.250974Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T19:49:16.251231Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T19:49:16.250973Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T19:49:16.251721Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T19:49:16.251813Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T19:49:16.252143Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T19:49:16.252245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T19:49:16.252279Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T19:49:16.255102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T19:49:16.255591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-07-15T19:49:25.155781Z","caller":"traceutil/trace.go:171","msg":"trace[1741190744] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"105.692793ms","start":"2026-07-15T19:49:25.050066Z","end":"2026-07-15T19:49:25.155759Z","steps":["trace[1741190744] 'process raft request' (duration: 94.100115ms)","trace[1741190744] 'compare' (duration: 11.258164ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T19:49:25.156175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.481168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-15T19:49:25.156369Z","caller":"traceutil/trace.go:171","msg":"trace[999023446] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:312; }","duration":"100.684486ms","start":"2026-07-15T19:49:25.055628Z","end":"2026-07-15T19:49:25.156312Z","steps":["trace[999023446] 'agreement among raft nodes before linearized reading' (duration: 100.446616ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:31.575073Z","caller":"traceutil/trace.go:171","msg":"trace[1925764163] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"487.994337ms","start":"2026-07-15T19:49:31.087042Z","end":"2026-07-15T19:49:31.575037Z","steps":["trace[1925764163] 'read index received' (duration: 487.527809ms)","trace[1925764163] 'applied index is now lower than readState.Index' (duration: 284.561µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T19:49:31.575127Z","caller":"traceutil/trace.go:171","msg":"trace[1559088562] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"525.367021ms","start":"2026-07-15T19:49:31.049728Z","end":"2026-07-15T19:49:31.575095Z","steps":["trace[1559088562] 'process raft request' (duration: 524.885413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:49:31.575226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.164293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T19:49:31.575282Z","caller":"traceutil/trace.go:171","msg":"trace[1353355547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"488.259487ms","start":"2026-07-15T19:49:31.087005Z","end":"2026-07-15T19:49:31.575265Z","steps":["trace[1353355547] 'agreement among raft nodes before linearized reading' (duration: 488.164263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:49:31.575331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:49:31.086991Z","time spent":"488.32905ms","remote":"127.0.0.1:54402","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-07-15T19:49:31.575941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:49:31.049704Z","time spent":"525.498917ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T19:49:31.649298Z","caller":"traceutil/trace.go:171","msg":"trace[1356158830] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"488.034479ms","start":"2026-07-15T19:49:31.161226Z","end":"2026-07-15T19:49:31.64926Z","steps":["trace[1356158830] 'process raft request' (duration: 487.81294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:49:31.649476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:49:31.1612Z","time spent":"488.179864ms","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T19:49:52.739267Z","caller":"traceutil/trace.go:171","msg":"trace[163812372] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"283.737907ms","start":"2026-07-15T19:49:52.455502Z","end":"2026-07-15T19:49:52.73924Z","steps":["trace[163812372] 'process raft request' (duration: 283.572491ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.751912Z","caller":"traceutil/trace.go:171","msg":"trace[502912806] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"286.701713ms","start":"2026-07-15T19:49:52.465188Z","end":"2026-07-15T19:49:52.75189Z","steps":["trace[502912806] 'process raft request' (duration: 286.586709ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.751986Z","caller":"traceutil/trace.go:171","msg":"trace[356453837] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"286.903479ms","start":"2026-07-15T19:49:52.465068Z","end":"2026-07-15T19:49:52.751971Z","steps":["trace[356453837] 'process raft request' (duration: 286.561298ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752133Z","caller":"traceutil/trace.go:171","msg":"trace[1532170877] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"281.994231ms","start":"2026-07-15T19:49:52.470131Z","end":"2026-07-15T19:49:52.752125Z","steps":["trace[1532170877] 'process raft request' (duration: 281.740073ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752224Z","caller":"traceutil/trace.go:171","msg":"trace[776813185] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"286.994312ms","start":"2026-07-15T19:49:52.465223Z","end":"2026-07-15T19:49:52.752218Z","steps":["trace[776813185] 'process raft request' (duration: 286.597399ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752317Z","caller":"traceutil/trace.go:171","msg":"trace[1142139355] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"113.241311ms","start":"2026-07-15T19:49:52.639059Z","end":"2026-07-15T19:49:52.752301Z","steps":["trace[1142139355] 'process raft request' (duration: 113.200109ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752356Z","caller":"traceutil/trace.go:171","msg":"trace[861958019] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"285.772682ms","start":"2026-07-15T19:49:52.466566Z","end":"2026-07-15T19:49:52.752339Z","steps":["trace[861958019] 'process raft request' (duration: 285.283656ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752372Z","caller":"traceutil/trace.go:171","msg":"trace[530834262] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"268.897649ms","start":"2026-07-15T19:49:52.483466Z","end":"2026-07-15T19:49:52.752364Z","steps":["trace[530834262] 'process raft request' (duration: 268.575498ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752368Z","caller":"traceutil/trace.go:171","msg":"trace[1667489986] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"276.753551ms","start":"2026-07-15T19:49:52.475593Z","end":"2026-07-15T19:49:52.752347Z","steps":["trace[1667489986] 'process raft request' (duration: 276.38908ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752392Z","caller":"traceutil/trace.go:171","msg":"trace[212985356] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"248.224493ms","start":"2026-07-15T19:49:52.504161Z","end":"2026-07-15T19:49:52.752386Z","steps":["trace[212985356] 'process raft request' (duration: 247.957834ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752394Z","caller":"traceutil/trace.go:171","msg":"trace[1351408541] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"280.968348ms","start":"2026-07-15T19:49:52.471416Z","end":"2026-07-15T19:49:52.752384Z","steps":["trace[1351408541] 'process raft request' (duration: 280.515023ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:52.752402Z","caller":"traceutil/trace.go:171","msg":"trace[868198538] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"280.805083ms","start":"2026-07-15T19:49:52.471588Z","end":"2026-07-15T19:49:52.752393Z","steps":["trace[868198538] 'process raft request' (duration: 280.365569ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:58.785938Z","caller":"traceutil/trace.go:171","msg":"trace[1716838691] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"249.917572ms","start":"2026-07-15T19:49:58.535989Z","end":"2026-07-15T19:49:58.785907Z","steps":["trace[1716838691] 'process raft request' (duration: 248.86768ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:59.238278Z","caller":"traceutil/trace.go:171","msg":"trace[393407476] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"149.802492ms","start":"2026-07-15T19:49:59.088448Z","end":"2026-07-15T19:49:59.238251Z","steps":["trace[393407476] 'read index received' (duration: 149.037999ms)","trace[393407476] 'applied index is now lower than readState.Index' (duration: 763.473µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T19:49:59.238363Z","caller":"traceutil/trace.go:171","msg":"trace[776466319] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"699.02702ms","start":"2026-07-15T19:49:58.539286Z","end":"2026-07-15T19:49:59.238313Z","steps":["trace[776466319] 'process raft request' (duration: 698.450393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:49:59.238408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.950106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T19:49:59.238573Z","caller":"traceutil/trace.go:171","msg":"trace[716210968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"150.130262ms","start":"2026-07-15T19:49:59.088434Z","end":"2026-07-15T19:49:59.238564Z","steps":["trace[716210968] 'agreement among raft nodes before linearized reading' (duration: 149.930606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:49:59.23862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:49:58.539258Z","time spent":"699.196235ms","remote":"127.0.0.1:54504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T19:49:59.413458Z","caller":"traceutil/trace.go:171","msg":"trace[1497417370] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"152.39719ms","start":"2026-07-15T19:49:59.261039Z","end":"2026-07-15T19:49:59.413437Z","steps":["trace[1497417370] 'read index received' (duration: 152.314368ms)","trace[1497417370] 'applied index is now lower than readState.Index' (duration: 82.002µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T19:49:59.413565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.504165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T19:49:59.413589Z","caller":"traceutil/trace.go:171","msg":"trace[1677880234] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:595; }","duration":"152.566406ms","start":"2026-07-15T19:49:59.261015Z","end":"2026-07-15T19:49:59.413582Z","steps":["trace[1677880234] 'agreement among raft nodes before linearized reading' (duration: 152.504224ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:59.413581Z","caller":"traceutil/trace.go:171","msg":"trace[1733844141] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"160.56842ms","start":"2026-07-15T19:49:59.252954Z","end":"2026-07-15T19:49:59.413522Z","steps":["trace[1733844141] 'process raft request' (duration: 160.374654ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:49:59.420717Z","caller":"traceutil/trace.go:171","msg":"trace[404722546] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"126.578755ms","start":"2026-07-15T19:49:59.294128Z","end":"2026-07-15T19:49:59.420707Z","steps":["trace[404722546] 'process raft request' (duration: 126.482332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:50:00.432891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369047374951737,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T19:50:00.444337Z","caller":"traceutil/trace.go:171","msg":"trace[906953231] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"512.499242ms","start":"2026-07-15T19:49:59.931816Z","end":"2026-07-15T19:50:00.444315Z","steps":["trace[906953231] 'read index received' (duration: 509.029987ms)","trace[906953231] 'applied index is now lower than readState.Index' (duration: 3.468365ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T19:50:00.444394Z","caller":"traceutil/trace.go:171","msg":"trace[1885157482] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"625.788211ms","start":"2026-07-15T19:49:59.818578Z","end":"2026-07-15T19:50:00.444366Z","steps":["trace[1885157482] 'process raft request' (duration: 622.470281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:50:00.445061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.957463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-15T19:50:00.444724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.896243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-15T19:50:00.445294Z","caller":"traceutil/trace.go:171","msg":"trace[1379488837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"513.46785ms","start":"2026-07-15T19:49:59.93176Z","end":"2026-07-15T19:50:00.445228Z","steps":["trace[1379488837] 'agreement among raft nodes before linearized reading' (duration: 512.691277ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:50:00.445163Z","caller":"traceutil/trace.go:171","msg":"trace[1500396305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"207.112447ms","start":"2026-07-15T19:50:00.238036Z","end":"2026-07-15T19:50:00.445149Z","steps":["trace[1500396305] 'agreement among raft nodes before linearized reading' (duration: 206.849009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:50:00.444926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.711844ms","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-07-15T19:50:00.445293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.439553ms","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-07-15T19:50:00.445376Z","caller":"traceutil/trace.go:171","msg":"trace[1110447605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"461.193429ms","start":"2026-07-15T19:49:59.984163Z","end":"2026-07-15T19:50:00.445356Z","steps":["trace[1110447605] 'agreement among raft nodes before linearized reading' (duration: 460.703554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:50:00.445454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:49:59.984132Z","time spent":"461.302013ms","remote":"127.0.0.1:54666","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":"info","ts":"2026-07-15T19:50:00.44547Z","caller":"traceutil/trace.go:171","msg":"trace[1852450961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"453.67738ms","start":"2026-07-15T19:49:59.991741Z","end":"2026-07-15T19:50:00.445418Z","steps":["trace[1852450961] 'agreement among raft nodes before linearized reading' (duration: 453.183375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:50:00.445628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:49:59.931739Z","time spent":"513.594755ms","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T19:50:00.445632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:49:59.99172Z","time spent":"453.897787ms","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T19:50:00.445769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:49:59.818555Z","time spent":"627.102491ms","remote":"127.0.0.1:54504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T19:50:13.45972Z","caller":"traceutil/trace.go:171","msg":"trace[1842447256] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"218.393281ms","start":"2026-07-15T19:50:13.241288Z","end":"2026-07-15T19:50:13.459681Z","steps":["trace[1842447256] 'process raft request' (duration: 218.225756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:50:16.323545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.732322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T19:50:16.323629Z","caller":"traceutil/trace.go:171","msg":"trace[1385952397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"235.861266ms","start":"2026-07-15T19:50:16.087746Z","end":"2026-07-15T19:50:16.323608Z","steps":["trace[1385952397] 'range keys from in-memory index tree' (duration: 235.66236ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:50:16.43631Z","caller":"traceutil/trace.go:171","msg":"trace[1989729275] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"111.640539ms","start":"2026-07-15T19:50:16.324643Z","end":"2026-07-15T19:50:16.436284Z","steps":["trace[1989729275] 'read index received' (duration: 111.284279ms)","trace[1989729275] 'applied index is now lower than readState.Index' (duration: 354.91µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T19:50:16.436436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.778443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T19:50:16.436438Z","caller":"traceutil/trace.go:171","msg":"trace[1013739423] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"177.455438ms","start":"2026-07-15T19:50:16.258939Z","end":"2026-07-15T19:50:16.436395Z","steps":["trace[1013739423] 'process raft request' (duration: 177.128589ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:50:16.436485Z","caller":"traceutil/trace.go:171","msg":"trace[1351753562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"111.871735ms","start":"2026-07-15T19:50:16.324587Z","end":"2026-07-15T19:50:16.436459Z","steps":["trace[1351753562] 'agreement among raft nodes before linearized reading' (duration: 111.795463ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:52:11.270098Z","caller":"traceutil/trace.go:171","msg":"trace[308935216] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1357; }","duration":"121.937352ms","start":"2026-07-15T19:52:11.148136Z","end":"2026-07-15T19:52:11.270074Z","steps":["trace[308935216] 'read index received' (duration: 20.806215ms)","trace[308935216] 'applied index is now lower than readState.Index' (duration: 101.130257ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T19:52:11.270245Z","caller":"traceutil/trace.go:171","msg":"trace[1052184078] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"122.823317ms","start":"2026-07-15T19:52:11.147409Z","end":"2026-07-15T19:52:11.270232Z","steps":["trace[1052184078] 'process raft request' (duration: 94.512963ms)","trace[1052184078] 'compare' (duration: 28.008589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T19:52:11.270495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.338539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-15T19:52:11.270592Z","caller":"traceutil/trace.go:171","msg":"trace[1542345061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"122.467661ms","start":"2026-07-15T19:52:11.148104Z","end":"2026-07-15T19:52:11.270572Z","steps":["trace[1542345061] 'agreement among raft nodes before linearized reading' (duration: 122.238707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:11.270752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.43979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-15T19:52:11.27146Z","caller":"traceutil/trace.go:171","msg":"trace[2124683425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"123.183923ms","start":"2026-07-15T19:52:11.148247Z","end":"2026-07-15T19:52:11.271431Z","steps":["trace[2124683425] 'agreement among raft nodes before linearized reading' (duration: 122.092695ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T19:52:26.162409Z","caller":"traceutil/trace.go:171","msg":"trace[1461731713] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"295.725753ms","start":"2026-07-15T19:52:25.866655Z","end":"2026-07-15T19:52:26.162381Z","steps":["trace[1461731713] 'read index received' (duration: 295.48897ms)","trace[1461731713] 'applied index is now lower than readState.Index' (duration: 235.713µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T19:52:26.162481Z","caller":"traceutil/trace.go:171","msg":"trace[1599618409] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"358.823916ms","start":"2026-07-15T19:52:25.803618Z","end":"2026-07-15T19:52:26.162442Z","steps":["trace[1599618409] 'process raft request' (duration: 358.591953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:26.162656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.97886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T19:52:26.162717Z","caller":"traceutil/trace.go:171","msg":"trace[735120713] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1364; }","duration":"265.099602ms","start":"2026-07-15T19:52:25.897605Z","end":"2026-07-15T19:52:26.162705Z","steps":["trace[735120713] 'agreement among raft nodes before linearized reading' (duration: 264.98203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:26.162773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:25.803609Z","time spent":"358.948539ms","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T19:52:26.162868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.625596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" ","response":"range_response_count:1 size:576"} {"level":"info","ts":"2026-07-15T19:52:26.162929Z","caller":"traceutil/trace.go:171","msg":"trace[632298797] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:1; response_revision:1364; }","duration":"248.726618ms","start":"2026-07-15T19:52:25.914184Z","end":"2026-07-15T19:52:26.16291Z","steps":["trace[632298797] 'agreement among raft nodes before linearized reading' (duration: 248.543365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:26.162653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.973068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-07-15T19:52:26.163334Z","caller":"traceutil/trace.go:171","msg":"trace[124734537] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1364; }","duration":"296.696889ms","start":"2026-07-15T19:52:25.866624Z","end":"2026-07-15T19:52:26.163321Z","steps":["trace[124734537] 'agreement among raft nodes before linearized reading' (duration: 295.938626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:27.043839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369047374953794,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T19:52:27.176399Z","caller":"traceutil/trace.go:171","msg":"trace[1080776889] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"794.024595ms","start":"2026-07-15T19:52:26.382342Z","end":"2026-07-15T19:52:27.176367Z","steps":["trace[1080776889] 'process raft request' (duration: 793.839062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:27.176586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:26.382319Z","time spent":"794.186557ms","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T19:52:27.176706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.365216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:1 size:1381"} {"level":"info","ts":"2026-07-15T19:52:27.176823Z","caller":"traceutil/trace.go:171","msg":"trace[1223239458] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:1365; }","duration":"633.532459ms","start":"2026-07-15T19:52:26.543273Z","end":"2026-07-15T19:52:27.176806Z","steps":["trace[1223239458] 'agreement among raft nodes before linearized reading' (duration: 633.183573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:27.176926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:26.543255Z","time spent":"633.653451ms","remote":"127.0.0.1:54750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":1404,"request content":"key:\"/registry/clusterroles/system:kube-scheduler\" "} {"level":"info","ts":"2026-07-15T19:52:27.17639Z","caller":"traceutil/trace.go:171","msg":"trace[577541647] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"633.00932ms","start":"2026-07-15T19:52:26.543321Z","end":"2026-07-15T19:52:27.17633Z","steps":["trace[577541647] 'read index received' (duration: 632.805227ms)","trace[577541647] 'applied index is now lower than readState.Index' (duration: 203.063µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T19:52:27.177456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.871418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-07-15T19:52:27.177707Z","caller":"traceutil/trace.go:171","msg":"trace[561427776] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1365; }","duration":"630.162613ms","start":"2026-07-15T19:52:26.547524Z","end":"2026-07-15T19:52:27.177687Z","steps":["trace[561427776] 'agreement among raft nodes before linearized reading' (duration: 629.875108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:27.1778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:26.547501Z","time spent":"630.281094ms","remote":"127.0.0.1:54752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":794,"request content":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" "} {"level":"warn","ts":"2026-07-15T19:52:29.211763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369047374953807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T19:52:29.71274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334369047374953807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T19:52:29.747645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.047667993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-07-15T19:52:29.747797Z","caller":"traceutil/trace.go:171","msg":"trace[1202496957] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:1366; }","duration":"2.047844607s","start":"2026-07-15T19:52:27.699918Z","end":"2026-07-15T19:52:29.747762Z","steps":["trace[1202496957] 'range keys from in-memory index tree' (duration: 2.047337028s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.7478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.046713818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-15T19:52:29.747855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:27.6999Z","time spent":"2.047941328s","remote":"127.0.0.1:54750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":685,"request content":"key:\"/registry/clusterroles/system:kubelet-api-admin\" "} {"level":"info","ts":"2026-07-15T19:52:29.747884Z","caller":"traceutil/trace.go:171","msg":"trace[767255449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1366; }","duration":"2.046822869s","start":"2026-07-15T19:52:27.701039Z","end":"2026-07-15T19:52:29.747862Z","steps":["trace[767255449] 'range keys from in-memory index tree' (duration: 2.046547194s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.747876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661354843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T19:52:29.747929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:27.701033Z","time spent":"2.04688761s","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-15T19:52:29.747949Z","caller":"traceutil/trace.go:171","msg":"trace[1381380180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1366; }","duration":"1.661460995s","start":"2026-07-15T19:52:28.086466Z","end":"2026-07-15T19:52:29.747926Z","steps":["trace[1381380180] 'range keys from in-memory index tree' (duration: 1.661290072s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.747995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:28.086449Z","time spent":"1.661533906s","remote":"127.0.0.1:54402","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-07-15T19:52:29.748028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.42107244s","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-07-15T19:52:29.748075Z","caller":"traceutil/trace.go:171","msg":"trace[1464474111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1366; }","duration":"2.421151121s","start":"2026-07-15T19:52:27.326909Z","end":"2026-07-15T19:52:29.74806Z","steps":["trace[1464474111] 'range keys from in-memory index tree' (duration: 2.420900538s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.748109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:27.326893Z","time spent":"2.421208382s","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T19:52:29.748399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.101687913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:1 size:1381"} {"level":"info","ts":"2026-07-15T19:52:29.74844Z","caller":"traceutil/trace.go:171","msg":"trace[492360592] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:1366; }","duration":"2.101804755s","start":"2026-07-15T19:52:27.646623Z","end":"2026-07-15T19:52:29.748428Z","steps":["trace[492360592] 'range keys from in-memory index tree' (duration: 2.101531131s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.748469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:27.646602Z","time spent":"2.101859446s","remote":"127.0.0.1:54750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":1404,"request content":"key:\"/registry/clusterroles/system:kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T19:52:29.748455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06382902s","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-07-15T19:52:29.748529Z","caller":"traceutil/trace.go:171","msg":"trace[890805750] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1366; }","duration":"1.063905841s","start":"2026-07-15T19:52:28.684612Z","end":"2026-07-15T19:52:29.748518Z","steps":["trace[890805750] 'range keys from in-memory index tree' (duration: 1.06381751s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.748607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.048460376s","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-07-15T19:52:29.748647Z","caller":"traceutil/trace.go:171","msg":"trace[1957787960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1366; }","duration":"2.048517918s","start":"2026-07-15T19:52:27.700116Z","end":"2026-07-15T19:52:29.748634Z","steps":["trace[1957787960] 'range keys from in-memory index tree' (duration: 2.048321334s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.748675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:27.70011Z","time spent":"2.048558729s","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T19:52:29.751209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.584385654s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T19:52:29.751596Z","caller":"traceutil/trace.go:171","msg":"trace[185059927] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"1.043322179s","start":"2026-07-15T19:52:28.708254Z","end":"2026-07-15T19:52:29.751576Z","steps":["trace[185059927] 'read index received' (duration: 1.043089765s)","trace[185059927] 'applied index is now lower than readState.Index' (duration: 231.344µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T19:52:29.7521Z","caller":"traceutil/trace.go:171","msg":"trace[1716019335] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"1.585317299s","start":"2026-07-15T19:52:28.166766Z","end":"2026-07-15T19:52:29.752083Z","steps":["trace[1716019335] 'process raft request' (duration: 1.584635348s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.752212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:28.166754Z","time spent":"1.58538679s","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T19:52:29.752463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044180733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T19:52:29.752576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.467007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T19:52:29.752626Z","caller":"traceutil/trace.go:171","msg":"trace[377671880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1367; }","duration":"571.565699ms","start":"2026-07-15T19:52:29.181049Z","end":"2026-07-15T19:52:29.752615Z","steps":["trace[377671880] 'agreement among raft nodes before linearized reading' (duration: 571.442847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.752662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:29.181031Z","time spent":"571.6245ms","remote":"127.0.0.1:54666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T19:52:29.753007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.439885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T19:52:29.753047Z","caller":"traceutil/trace.go:171","msg":"trace[842199487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1367; }","duration":"186.518327ms","start":"2026-07-15T19:52:29.566519Z","end":"2026-07-15T19:52:29.753037Z","steps":["trace[842199487] 'agreement among raft nodes before linearized reading' (duration: 186.469036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.753211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.865363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T19:52:29.753269Z","caller":"traceutil/trace.go:171","msg":"trace[1064599721] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1367; }","duration":"483.946975ms","start":"2026-07-15T19:52:29.269314Z","end":"2026-07-15T19:52:29.753261Z","steps":["trace[1064599721] 'agreement among raft nodes before linearized reading' (duration: 483.836243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.753327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:29.269302Z","time spent":"484.018236ms","remote":"127.0.0.1:54442","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.109\" "} {"level":"info","ts":"2026-07-15T19:52:29.753471Z","caller":"traceutil/trace.go:171","msg":"trace[50350954] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1367; }","duration":"1.044301264s","start":"2026-07-15T19:52:28.708211Z","end":"2026-07-15T19:52:29.752512Z","steps":["trace[50350954] 'agreement among raft nodes before linearized reading' (duration: 1.044183372s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.7537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.689629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-15T19:52:29.753777Z","caller":"traceutil/trace.go:171","msg":"trace[693329088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1367; }","duration":"510.79327ms","start":"2026-07-15T19:52:29.24297Z","end":"2026-07-15T19:52:29.753763Z","steps":["trace[693329088] 'agreement among raft nodes before linearized reading' (duration: 510.610867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T19:52:29.753826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:29.242957Z","time spent":"510.857862ms","remote":"127.0.0.1:54666","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-07-15T19:52:29.754262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T19:52:28.708192Z","time spent":"1.045372003s","remote":"127.0.0.1:54772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "}