{"level":"warn","ts":"2026-02-06T06:00:13.313645Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T06:00:13.320671Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-06T06:00:13.320759Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-06T06:00:13.320827Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-02-06T06:00:13.320855Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-06T06:00:13.321682Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-02-06T06:00:13.321847Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-06T06:00:13.330485Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.210303ms"} {"level":"info","ts":"2026-02-06T06:00:13.338095Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-02-06T06:00:13.338137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-02-06T06:00:13.338154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-02-06T06:00:13.338162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-06T06:00:13.338171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-02-06T06:00:13.338242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-02-06T06:00:13.355108Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-06T06:00:13.368312Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-06T06:00:13.373775Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-06T06:00:13.379134Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-06T06:00:13.383056Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-06T06:00:13.383247Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T06:00:13.383381Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T06:00:13.38339Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-06T06:00:13.38343Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-06T06:00:13.383776Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-06T06:00:13.38389Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-06T06:00:13.383968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-02-06T06:00:13.384065Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-02-06T06:00:13.384074Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-02-06T06:00:13.384023Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-02-06T06:00:14.039356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-02-06T06:00:14.039418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-06T06:00:14.039448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-02-06T06:00:14.039471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-02-06T06:00:14.039494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-02-06T06:00:14.039502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-02-06T06:00:14.039513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-02-06T06:00:14.042142Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T06:00:14.042409Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T06:00:14.042122Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-02-06T06:00:14.044071Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-02-06T06:00:14.044327Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T06:00:14.044383Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T06:00:14.044399Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-06T06:00:14.044407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-06T06:00:14.051284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-06T06:00:14.05201Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-06T06:00:14.052026Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-06T06:00:16.755105Z","caller":"traceutil/trace.go:171","msg":"trace[1067141515] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"150.698806ms","start":"2026-02-06T06:00:16.604316Z","end":"2026-02-06T06:00:16.755015Z","steps":["trace[1067141515] 'process raft request' (duration: 51.701843ms)","trace[1067141515] 'compare' (duration: 98.768437ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:17.066859Z","caller":"traceutil/trace.go:171","msg":"trace[312346017] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"131.121665ms","start":"2026-02-06T06:00:16.935655Z","end":"2026-02-06T06:00:17.066776Z","steps":["trace[312346017] 'process raft request' (duration: 82.583822ms)","trace[312346017] 'compare' (duration: 48.337218ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:18.354005Z","caller":"traceutil/trace.go:171","msg":"trace[1998322035] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"126.082727ms","start":"2026-02-06T06:00:18.227899Z","end":"2026-02-06T06:00:18.353981Z","steps":["trace[1998322035] 'process raft request' (duration: 91.065511ms)","trace[1998322035] 'compare' (duration: 34.880483ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:18.896519Z","caller":"traceutil/trace.go:171","msg":"trace[1099774847] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"117.23688ms","start":"2026-02-06T06:00:18.779184Z","end":"2026-02-06T06:00:18.896421Z","steps":["trace[1099774847] 'process raft request' (duration: 64.958602ms)","trace[1099774847] 'compare' (duration: 52.141274ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:24.714378Z","caller":"traceutil/trace.go:171","msg":"trace[861989675] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"134.818591ms","start":"2026-02-06T06:00:24.579545Z","end":"2026-02-06T06:00:24.714364Z","steps":["trace[861989675] 'process raft request' (duration: 119.241362ms)","trace[861989675] 'compare' (duration: 15.141977ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:36.103055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184135996087712,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-06T06:00:36.540495Z","caller":"traceutil/trace.go:171","msg":"trace[407190760] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"939.84906ms","start":"2026-02-06T06:00:35.60063Z","end":"2026-02-06T06:00:36.540479Z","steps":["trace[407190760] 'process raft request' (duration: 939.771778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:36.540804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:35.600618Z","time spent":"939.924531ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T06:00:36.557002Z","caller":"traceutil/trace.go:171","msg":"trace[1961966660] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"954.486946ms","start":"2026-02-06T06:00:35.602508Z","end":"2026-02-06T06:00:36.556995Z","steps":["trace[1961966660] 'read index received' (duration: 938.011514ms)","trace[1961966660] 'applied index is now lower than readState.Index' (duration: 16.474762ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:36.557107Z","caller":"traceutil/trace.go:171","msg":"trace[454295866] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"535.821651ms","start":"2026-02-06T06:00:36.021281Z","end":"2026-02-06T06:00:36.557103Z","steps":["trace[454295866] 'process raft request' (duration: 535.678718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:36.55714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:36.021261Z","time spent":"535.858642ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:36.55721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.699931ms","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-02-06T06:00:36.557225Z","caller":"traceutil/trace.go:171","msg":"trace[1243588924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"954.729171ms","start":"2026-02-06T06:00:35.602491Z","end":"2026-02-06T06:00:36.55722Z","steps":["trace[1243588924] 'agreement among raft nodes before linearized reading' (duration: 954.700151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:36.557237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:35.602485Z","time spent":"954.750102ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T06:00:36.5573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.037229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:36.557311Z","caller":"traceutil/trace.go:171","msg":"trace[231898337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"327.07862ms","start":"2026-02-06T06:00:36.230229Z","end":"2026-02-06T06:00:36.557308Z","steps":["trace[231898337] 'agreement among raft nodes before linearized reading' (duration: 327.059199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:36.557322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:36.230219Z","time spent":"327.100431ms","remote":"127.0.0.1:59940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T06:00:36.557412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.034689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-06T06:00:36.557426Z","caller":"traceutil/trace.go:171","msg":"trace[1415541353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"125.04827ms","start":"2026-02-06T06:00:36.432374Z","end":"2026-02-06T06:00:36.557422Z","steps":["trace[1415541353] 'agreement among raft nodes before linearized reading' (duration: 125.024489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:36.557482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.799824ms","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-02-06T06:00:36.557494Z","caller":"traceutil/trace.go:171","msg":"trace[1589622460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"312.822164ms","start":"2026-02-06T06:00:36.244667Z","end":"2026-02-06T06:00:36.557489Z","steps":["trace[1589622460] 'agreement among raft nodes before linearized reading' (duration: 312.801724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:36.557505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:36.244662Z","time spent":"312.839795ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-06T06:00:36.848823Z","caller":"traceutil/trace.go:171","msg":"trace[1641393852] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"282.693404ms","start":"2026-02-06T06:00:36.566115Z","end":"2026-02-06T06:00:36.848808Z","steps":["trace[1641393852] 'read index received' (duration: 250.502501ms)","trace[1641393852] 'applied index is now lower than readState.Index' (duration: 32.190353ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:36.848929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.789196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:36.848951Z","caller":"traceutil/trace.go:171","msg":"trace[1973039355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"282.850128ms","start":"2026-02-06T06:00:36.566096Z","end":"2026-02-06T06:00:36.848946Z","steps":["trace[1973039355] 'agreement among raft nodes before linearized reading' (duration: 282.781867ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:36.849172Z","caller":"traceutil/trace.go:171","msg":"trace[1664763908] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"286.957133ms","start":"2026-02-06T06:00:36.562209Z","end":"2026-02-06T06:00:36.849166Z","steps":["trace[1664763908] 'process raft request' (duration: 254.397961ms)","trace[1664763908] 'compare' (duration: 32.138451ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:36.89969Z","caller":"traceutil/trace.go:171","msg":"trace[1332237142] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"333.485133ms","start":"2026-02-06T06:00:36.566187Z","end":"2026-02-06T06:00:36.899672Z","steps":["trace[1332237142] 'process raft request' (duration: 333.35652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:36.899829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:36.566164Z","time spent":"333.569256ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T06:00:36.899867Z","caller":"traceutil/trace.go:171","msg":"trace[339498527] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"332.370775ms","start":"2026-02-06T06:00:36.567481Z","end":"2026-02-06T06:00:36.899852Z","steps":["trace[339498527] 'process raft request' (duration: 332.146859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:36.89996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:36.567444Z","time spent":"332.460447ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:45.548983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.673748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:00:45.549079Z","caller":"traceutil/trace.go:171","msg":"trace[1465702533] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"617.559853ms","start":"2026-02-06T06:00:44.931502Z","end":"2026-02-06T06:00:45.549062Z","steps":["trace[1465702533] 'read index received' (duration: 208.521377ms)","trace[1465702533] 'applied index is now lower than readState.Index' (duration: 409.037386ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:45.549231Z","caller":"traceutil/trace.go:171","msg":"trace[1541984976] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"621.655647ms","start":"2026-02-06T06:00:44.927566Z","end":"2026-02-06T06:00:45.549222Z","steps":["trace[1541984976] 'process raft request' (duration: 212.531719ms)","trace[1541984976] 'compare' (duration: 408.464702ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:45.54927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:44.927541Z","time spent":"621.708029ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:45.549406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.902192ms","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-02-06T06:00:45.54942Z","caller":"traceutil/trace.go:171","msg":"trace[722126238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"617.930212ms","start":"2026-02-06T06:00:44.931485Z","end":"2026-02-06T06:00:45.549415Z","steps":["trace[722126238] 'agreement among raft nodes before linearized reading' (duration: 617.897581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:45.549436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:44.93148Z","time spent":"617.952583ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T06:00:45.549502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.254758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:45.549514Z","caller":"traceutil/trace.go:171","msg":"trace[100422733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"317.302049ms","start":"2026-02-06T06:00:45.232208Z","end":"2026-02-06T06:00:45.54951Z","steps":["trace[100422733] 'agreement among raft nodes before linearized reading' (duration: 317.280379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:45.549525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:45.232189Z","time spent":"317.33341ms","remote":"127.0.0.1:59940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T06:00:46.365814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.083093ms","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-02-06T06:00:46.365985Z","caller":"traceutil/trace.go:171","msg":"trace[2113620345] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:469; }","duration":"240.298969ms","start":"2026-02-06T06:00:46.125662Z","end":"2026-02-06T06:00:46.365961Z","steps":["trace[2113620345] 'range keys from in-memory index tree' (duration: 240.0317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:46.367146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.761564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:00:46.36721Z","caller":"traceutil/trace.go:171","msg":"trace[103290094] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"264.049895ms","start":"2026-02-06T06:00:46.103146Z","end":"2026-02-06T06:00:46.367196Z","steps":["trace[103290094] 'read index received' (duration: 42.451µs)","trace[103290094] 'applied index is now lower than readState.Index' (duration: 264.006204ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:46.367415Z","caller":"traceutil/trace.go:171","msg":"trace[587293429] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"813.481566ms","start":"2026-02-06T06:00:45.553917Z","end":"2026-02-06T06:00:46.367398Z","steps":["trace[587293429] 'process raft request' (duration: 530.381043ms)","trace[587293429] 'compare' (duration: 282.57824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:46.367482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:45.553903Z","time spent":"813.545688ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:46.367723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.581388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-02-06T06:00:46.367752Z","caller":"traceutil/trace.go:171","msg":"trace[954667146] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:470; }","duration":"264.60899ms","start":"2026-02-06T06:00:46.103133Z","end":"2026-02-06T06:00:46.367742Z","steps":["trace[954667146] 'agreement among raft nodes before linearized reading' (duration: 264.545668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:46.367922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.578479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:00:46.36795Z","caller":"traceutil/trace.go:171","msg":"trace[109271704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"135.647271ms","start":"2026-02-06T06:00:46.232294Z","end":"2026-02-06T06:00:46.367942Z","steps":["trace[109271704] 'agreement among raft nodes before linearized reading' (duration: 135.60343ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:47.149022Z","caller":"traceutil/trace.go:171","msg":"trace[278102421] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"255.667962ms","start":"2026-02-06T06:00:46.893324Z","end":"2026-02-06T06:00:47.148992Z","steps":["trace[278102421] 'process raft request' (duration: 255.534919ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:48.970114Z","caller":"traceutil/trace.go:171","msg":"trace[2002010494] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"265.144985ms","start":"2026-02-06T06:00:48.704945Z","end":"2026-02-06T06:00:48.97009Z","steps":["trace[2002010494] 'read index received' (duration: 264.96587ms)","trace[2002010494] 'applied index is now lower than readState.Index' (duration: 178.205µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:48.970315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.348031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-06T06:00:48.970342Z","caller":"traceutil/trace.go:171","msg":"trace[951867743] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:477; }","duration":"265.437713ms","start":"2026-02-06T06:00:48.704897Z","end":"2026-02-06T06:00:48.970334Z","steps":["trace[951867743] 'agreement among raft nodes before linearized reading' (duration: 265.289339ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:48.970712Z","caller":"traceutil/trace.go:171","msg":"trace[745706171] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"353.174977ms","start":"2026-02-06T06:00:48.617528Z","end":"2026-02-06T06:00:48.970703Z","steps":["trace[745706171] 'process raft request' (duration: 352.446639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:48.970788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:48.617497Z","time spent":"353.235569ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:49.390105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.415656ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-06T06:00:49.390239Z","caller":"traceutil/trace.go:171","msg":"trace[912570239] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"237.551389ms","start":"2026-02-06T06:00:49.152665Z","end":"2026-02-06T06:00:49.390216Z","steps":["trace[912570239] 'read index received' (duration: 92.945738ms)","trace[912570239] 'applied index is now lower than readState.Index' (duration: 144.604191ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:49.390272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:48.971646Z","time spent":"418.615611ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-06T06:00:49.390336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.664271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-06T06:00:49.390379Z","caller":"traceutil/trace.go:171","msg":"trace[1385632913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"237.760233ms","start":"2026-02-06T06:00:49.152604Z","end":"2026-02-06T06:00:49.390365Z","steps":["trace[1385632913] 'agreement among raft nodes before linearized reading' (duration: 237.665381ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:00:54.061128Z","caller":"traceutil/trace.go:171","msg":"trace[188740964] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"578.85196ms","start":"2026-02-06T06:00:53.482245Z","end":"2026-02-06T06:00:54.061097Z","steps":["trace[188740964] 'process raft request' (duration: 578.696197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:54.061418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:53.482232Z","time spent":"578.992773ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T06:00:54.061948Z","caller":"traceutil/trace.go:171","msg":"trace[1696325536] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:512; }","duration":"478.850217ms","start":"2026-02-06T06:00:53.582981Z","end":"2026-02-06T06:00:54.061832Z","steps":["trace[1696325536] 'read index received' (duration: 478.834987ms)","trace[1696325536] 'applied index is now lower than readState.Index' (duration: 12.45µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:54.062154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.151134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-06T06:00:54.06219Z","caller":"traceutil/trace.go:171","msg":"trace[344016918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"479.270257ms","start":"2026-02-06T06:00:53.582908Z","end":"2026-02-06T06:00:54.062178Z","steps":["trace[344016918] 'agreement among raft nodes before linearized reading' (duration: 479.122314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:54.062229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:53.582883Z","time spent":"479.337568ms","remote":"127.0.0.1:60210","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-02-06T06:00:55.101702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.378322ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-06T06:00:55.102824Z","caller":"traceutil/trace.go:171","msg":"trace[1298390223] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:512; }","duration":"967.003586ms","start":"2026-02-06T06:00:54.135793Z","end":"2026-02-06T06:00:55.102796Z","steps":["trace[1298390223] 'read index received' (duration: 397.527471ms)","trace[1298390223] 'applied index is now lower than readState.Index' (duration: 569.474285ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:55.102953Z","caller":"traceutil/trace.go:171","msg":"trace[650731584] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.036408683s","start":"2026-02-06T06:00:54.06653Z","end":"2026-02-06T06:00:55.102938Z","steps":["trace[650731584] 'process raft request' (duration: 1.035294968s)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:00:55.103153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:54.066507Z","time spent":"1.036478804s","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:00:55.145944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010094801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8ttxj\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-06T06:00:55.146035Z","caller":"traceutil/trace.go:171","msg":"trace[1075124078] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8ttxj; range_end:; response_count:1; response_revision:493; }","duration":"1.010247094s","start":"2026-02-06T06:00:54.135761Z","end":"2026-02-06T06:00:55.146008Z","steps":["trace[1075124078] 'agreement among raft nodes before linearized reading' (duration: 967.603849ms)","trace[1075124078] 'range keys from in-memory index tree' (duration: 42.291767ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:55.14608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:54.135752Z","time spent":"1.010316695s","remote":"127.0.0.1:60142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-8ttxj\" "} {"level":"warn","ts":"2026-02-06T06:00:55.146133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.656845ms","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-02-06T06:00:55.14617Z","caller":"traceutil/trace.go:171","msg":"trace[1432451735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"749.740047ms","start":"2026-02-06T06:00:54.396417Z","end":"2026-02-06T06:00:55.146157Z","steps":["trace[1432451735] 'agreement among raft nodes before linearized reading' (duration: 706.97138ms)","trace[1432451735] 'range keys from in-memory index tree' (duration: 42.707935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:00:55.146193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:00:54.396392Z","time spent":"749.794499ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-06T06:00:55.146599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.820669ms","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-02-06T06:00:55.146634Z","caller":"traceutil/trace.go:171","msg":"trace[346313922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"146.922953ms","start":"2026-02-06T06:00:54.999696Z","end":"2026-02-06T06:00:55.146619Z","steps":["trace[346313922] 'agreement among raft nodes before linearized reading' (duration: 103.738477ms)","trace[346313922] 'range keys from in-memory index tree' (duration: 43.111974ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:00:57.568085Z","caller":"traceutil/trace.go:171","msg":"trace[2135896797] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"192.593133ms","start":"2026-02-06T06:00:57.37546Z","end":"2026-02-06T06:00:57.568053Z","steps":["trace[2135896797] 'process raft request' (duration: 192.45931ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:00.5157Z","caller":"traceutil/trace.go:171","msg":"trace[2030664281] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"230.38045ms","start":"2026-02-06T06:01:00.28506Z","end":"2026-02-06T06:01:00.51544Z","steps":["trace[2030664281] 'process raft request' (duration: 230.090424ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:00.522762Z","caller":"traceutil/trace.go:171","msg":"trace[112441867] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"116.334995ms","start":"2026-02-06T06:01:00.406418Z","end":"2026-02-06T06:01:00.522753Z","steps":["trace[112441867] 'process raft request' (duration: 116.271164ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:05.529058Z","caller":"traceutil/trace.go:171","msg":"trace[126518538] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"197.295207ms","start":"2026-02-06T06:01:05.331746Z","end":"2026-02-06T06:01:05.529041Z","steps":["trace[126518538] 'process raft request' (duration: 197.202815ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:05.895494Z","caller":"traceutil/trace.go:171","msg":"trace[1006890149] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"157.366914ms","start":"2026-02-06T06:01:05.738106Z","end":"2026-02-06T06:01:05.895473Z","steps":["trace[1006890149] 'read index received' (duration: 157.222841ms)","trace[1006890149] 'applied index is now lower than readState.Index' (duration: 143.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:01:05.89567Z","caller":"traceutil/trace.go:171","msg":"trace[2063713765] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"541.661752ms","start":"2026-02-06T06:01:05.354Z","end":"2026-02-06T06:01:05.895661Z","steps":["trace[2063713765] 'process raft request' (duration: 541.349895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:05.895727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:01:05.35398Z","time spent":"541.713083ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-06T06:01:05.898325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.214168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-06T06:01:05.898375Z","caller":"traceutil/trace.go:171","msg":"trace[1534195378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:610; }","duration":"160.30875ms","start":"2026-02-06T06:01:05.738054Z","end":"2026-02-06T06:01:05.898363Z","steps":["trace[1534195378] 'agreement among raft nodes before linearized reading' (duration: 157.497078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:08.657878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.049733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:01:08.657937Z","caller":"traceutil/trace.go:171","msg":"trace[511855490] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"222.396523ms","start":"2026-02-06T06:01:08.435531Z","end":"2026-02-06T06:01:08.657928Z","steps":["trace[511855490] 'read index received' (duration: 72.201887ms)","trace[511855490] 'applied index is now lower than readState.Index' (duration: 150.194006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:01:08.658056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.517275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3769"} {"level":"info","ts":"2026-02-06T06:01:08.658071Z","caller":"traceutil/trace.go:171","msg":"trace[974600122] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:663; }","duration":"222.549607ms","start":"2026-02-06T06:01:08.435515Z","end":"2026-02-06T06:01:08.658065Z","steps":["trace[974600122] 'agreement among raft nodes before linearized reading' (duration: 222.435985ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:08.658166Z","caller":"traceutil/trace.go:171","msg":"trace[1551021671] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"223.147091ms","start":"2026-02-06T06:01:08.435012Z","end":"2026-02-06T06:01:08.65816Z","steps":["trace[1551021671] 'process raft request' (duration: 72.759301ms)","trace[1551021671] 'compare' (duration: 149.806387ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:01:16.320168Z","caller":"traceutil/trace.go:171","msg":"trace[504520969] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"119.652846ms","start":"2026-02-06T06:01:16.200495Z","end":"2026-02-06T06:01:16.320148Z","steps":["trace[504520969] 'process raft request' (duration: 119.264778ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:17.364383Z","caller":"traceutil/trace.go:171","msg":"trace[590881381] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"164.610191ms","start":"2026-02-06T06:01:17.199756Z","end":"2026-02-06T06:01:17.364366Z","steps":["trace[590881381] 'read index received' (duration: 164.465757ms)","trace[590881381] 'applied index is now lower than readState.Index' (duration: 143.724µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:01:17.364593Z","caller":"traceutil/trace.go:171","msg":"trace[1906509888] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"187.940226ms","start":"2026-02-06T06:01:17.176643Z","end":"2026-02-06T06:01:17.364584Z","steps":["trace[1906509888] 'process raft request' (duration: 187.62559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:17.364788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.018639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-06T06:01:17.364809Z","caller":"traceutil/trace.go:171","msg":"trace[1843772573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"165.078711ms","start":"2026-02-06T06:01:17.199724Z","end":"2026-02-06T06:01:17.364803Z","steps":["trace[1843772573] 'agreement among raft nodes before linearized reading' (duration: 164.984589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:17.364904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.993061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:01:17.36492Z","caller":"traceutil/trace.go:171","msg":"trace[776217604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"133.030192ms","start":"2026-02-06T06:01:17.231885Z","end":"2026-02-06T06:01:17.364915Z","steps":["trace[776217604] 'agreement among raft nodes before linearized reading' (duration: 133.004971ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:22.769313Z","caller":"traceutil/trace.go:171","msg":"trace[1838543845] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"430.664073ms","start":"2026-02-06T06:01:22.33863Z","end":"2026-02-06T06:01:22.769294Z","steps":["trace[1838543845] 'process raft request' (duration: 430.574631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:22.769442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:01:22.338617Z","time spent":"430.752405ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T06:01:22.879625Z","caller":"traceutil/trace.go:171","msg":"trace[438516040] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"342.161267ms","start":"2026-02-06T06:01:22.53745Z","end":"2026-02-06T06:01:22.879611Z","steps":["trace[438516040] 'process raft request' (duration: 342.069975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:22.879713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:01:22.537427Z","time spent":"342.239029ms","remote":"127.0.0.1:60134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:01:30.485496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.742552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:01:30.485567Z","caller":"traceutil/trace.go:171","msg":"trace[1785677714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"253.854884ms","start":"2026-02-06T06:01:30.231699Z","end":"2026-02-06T06:01:30.485554Z","steps":["trace[1785677714] 'range keys from in-memory index tree' (duration: 253.678001ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:32.96825Z","caller":"traceutil/trace.go:171","msg":"trace[1845440539] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"168.783382ms","start":"2026-02-06T06:01:32.79945Z","end":"2026-02-06T06:01:32.968233Z","steps":["trace[1845440539] 'process raft request' (duration: 168.67629ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:35.778414Z","caller":"traceutil/trace.go:171","msg":"trace[1150679163] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"304.633057ms","start":"2026-02-06T06:01:35.473758Z","end":"2026-02-06T06:01:35.778391Z","steps":["trace[1150679163] 'read index received' (duration: 304.456893ms)","trace[1150679163] 'applied index is now lower than readState.Index' (duration: 175.284µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:01:35.778656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.877503ms","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-02-06T06:01:35.778688Z","caller":"traceutil/trace.go:171","msg":"trace[717576564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:745; }","duration":"304.937864ms","start":"2026-02-06T06:01:35.473741Z","end":"2026-02-06T06:01:35.778679Z","steps":["trace[717576564] 'agreement among raft nodes before linearized reading' (duration: 304.77249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:35.778719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:01:35.473707Z","time spent":"305.005236ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-06T06:01:35.778758Z","caller":"traceutil/trace.go:171","msg":"trace[1639701011] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"306.514048ms","start":"2026-02-06T06:01:35.472228Z","end":"2026-02-06T06:01:35.778742Z","steps":["trace[1639701011] 'process raft request' (duration: 306.056569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:35.778853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:01:35.472216Z","time spent":"306.57937ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T06:01:35.974617Z","caller":"traceutil/trace.go:171","msg":"trace[1209045705] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"187.554688ms","start":"2026-02-06T06:01:35.787038Z","end":"2026-02-06T06:01:35.974593Z","steps":["trace[1209045705] 'process raft request' (duration: 166.425181ms)","trace[1209045705] 'compare' (duration: 20.987464ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:01:35.974867Z","caller":"traceutil/trace.go:171","msg":"trace[1552720075] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"186.083905ms","start":"2026-02-06T06:01:35.788769Z","end":"2026-02-06T06:01:35.974852Z","steps":["trace[1552720075] 'process raft request' (duration: 186.031834ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:42.025932Z","caller":"traceutil/trace.go:171","msg":"trace[1881650181] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"227.759937ms","start":"2026-02-06T06:01:41.798152Z","end":"2026-02-06T06:01:42.025912Z","steps":["trace[1881650181] 'process raft request' (duration: 227.648085ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:42.026706Z","caller":"traceutil/trace.go:171","msg":"trace[705979516] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"122.98687ms","start":"2026-02-06T06:01:41.903567Z","end":"2026-02-06T06:01:42.026554Z","steps":["trace[705979516] 'read index received' (duration: 122.11877ms)","trace[705979516] 'applied index is now lower than readState.Index' (duration: 866.9µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:01:42.026928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.274186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:01:42.027185Z","caller":"traceutil/trace.go:171","msg":"trace[1390951505] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:762; }","duration":"123.622324ms","start":"2026-02-06T06:01:41.903549Z","end":"2026-02-06T06:01:42.027171Z","steps":["trace[1390951505] 'agreement among raft nodes before linearized reading' (duration: 123.261886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:42.217032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.79005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:01:42.217905Z","caller":"traceutil/trace.go:171","msg":"trace[945209277] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:793; }","duration":"135.776594ms","start":"2026-02-06T06:01:42.082114Z","end":"2026-02-06T06:01:42.217891Z","steps":["trace[945209277] 'read index received' (duration: 100.868851ms)","trace[945209277] 'applied index is now lower than readState.Index' (duration: 34.906793ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:01:42.218122Z","caller":"traceutil/trace.go:171","msg":"trace[617650936] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"187.393924ms","start":"2026-02-06T06:01:42.030713Z","end":"2026-02-06T06:01:42.218107Z","steps":["trace[617650936] 'process raft request' (duration: 33.45909ms)","trace[617650936] 'compare' (duration: 152.634065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:01:42.218141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.027269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-06T06:01:42.218334Z","caller":"traceutil/trace.go:171","msg":"trace[1151700641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"136.239873ms","start":"2026-02-06T06:01:42.082084Z","end":"2026-02-06T06:01:42.218324Z","steps":["trace[1151700641] 'agreement among raft nodes before linearized reading' (duration: 135.893136ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:42.220453Z","caller":"traceutil/trace.go:171","msg":"trace[1604351780] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"189.012501ms","start":"2026-02-06T06:01:42.031427Z","end":"2026-02-06T06:01:42.22044Z","steps":["trace[1604351780] 'process raft request' (duration: 186.414524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:42.498242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.379319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:01:42.498429Z","caller":"traceutil/trace.go:171","msg":"trace[490427111] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"276.058756ms","start":"2026-02-06T06:01:42.222268Z","end":"2026-02-06T06:01:42.498327Z","steps":["trace[490427111] 'process raft request' (duration: 276.034545ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:42.498646Z","caller":"traceutil/trace.go:171","msg":"trace[1026129305] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"298.304937ms","start":"2026-02-06T06:01:42.200334Z","end":"2026-02-06T06:01:42.498639Z","steps":["trace[1026129305] 'process raft request' (duration: 155.472107ms)","trace[1026129305] 'compare' (duration: 142.314068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:01:43.078884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.499298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T06:01:43.07899Z","caller":"traceutil/trace.go:171","msg":"trace[1207009429] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:766; }","duration":"386.695692ms","start":"2026-02-06T06:01:42.692275Z","end":"2026-02-06T06:01:43.078971Z","steps":["trace[1207009429] 'count revisions from in-memory index tree' (duration: 386.441976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:43.079043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:01:42.69226Z","time spent":"386.772025ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-06T06:01:55.486522Z","caller":"traceutil/trace.go:171","msg":"trace[1127495287] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"336.890501ms","start":"2026-02-06T06:01:55.149611Z","end":"2026-02-06T06:01:55.486502Z","steps":["trace[1127495287] 'process raft request' (duration: 336.37873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:55.486641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:01:55.149601Z","time spent":"336.974903ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:01:56.427197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.025326ms","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-02-06T06:01:56.427246Z","caller":"traceutil/trace.go:171","msg":"trace[1696608892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:855; }","duration":"125.159489ms","start":"2026-02-06T06:01:56.302075Z","end":"2026-02-06T06:01:56.427234Z","steps":["trace[1696608892] 'range keys from in-memory index tree' (duration: 124.868402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:01:56.42747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.395204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-06T06:01:56.427491Z","caller":"traceutil/trace.go:171","msg":"trace[1130345636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:855; }","duration":"125.439474ms","start":"2026-02-06T06:01:56.302047Z","end":"2026-02-06T06:01:56.427486Z","steps":["trace[1130345636] 'range keys from in-memory index tree' (duration: 125.330633ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:01:57.615459Z","caller":"traceutil/trace.go:171","msg":"trace[388786289] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"122.856227ms","start":"2026-02-06T06:01:57.492588Z","end":"2026-02-06T06:01:57.615444Z","steps":["trace[388786289] 'process raft request' (duration: 122.771125ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:02.867006Z","caller":"traceutil/trace.go:171","msg":"trace[1539863361] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"284.876351ms","start":"2026-02-06T06:02:02.582114Z","end":"2026-02-06T06:02:02.866991Z","steps":["trace[1539863361] 'process raft request' (duration: 284.780569ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:03.200913Z","caller":"traceutil/trace.go:171","msg":"trace[1696720104] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"191.340641ms","start":"2026-02-06T06:02:03.009549Z","end":"2026-02-06T06:02:03.200889Z","steps":["trace[1696720104] 'process raft request' (duration: 191.192688ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:07.977747Z","caller":"traceutil/trace.go:171","msg":"trace[818026245] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"240.662902ms","start":"2026-02-06T06:02:07.737062Z","end":"2026-02-06T06:02:07.977725Z","steps":["trace[818026245] 'read index received' (duration: 240.518499ms)","trace[818026245] 'applied index is now lower than readState.Index' (duration: 143.863µs)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:02:07.977849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.767595ms","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-02-06T06:02:07.977866Z","caller":"traceutil/trace.go:171","msg":"trace[361919280] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:887; }","duration":"240.829866ms","start":"2026-02-06T06:02:07.737031Z","end":"2026-02-06T06:02:07.977861Z","steps":["trace[361919280] 'agreement among raft nodes before linearized reading' (duration: 240.759385ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:07.977999Z","caller":"traceutil/trace.go:171","msg":"trace[1384494217] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"248.27103ms","start":"2026-02-06T06:02:07.729708Z","end":"2026-02-06T06:02:07.977979Z","steps":["trace[1384494217] 'process raft request' (duration: 247.911192ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:13.815536Z","caller":"traceutil/trace.go:171","msg":"trace[1447110377] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"116.761792ms","start":"2026-02-06T06:02:13.698759Z","end":"2026-02-06T06:02:13.815521Z","steps":["trace[1447110377] 'read index received' (duration: 116.643419ms)","trace[1447110377] 'applied index is now lower than readState.Index' (duration: 117.763µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:02:13.815616Z","caller":"traceutil/trace.go:171","msg":"trace[210641523] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"507.800471ms","start":"2026-02-06T06:02:13.307811Z","end":"2026-02-06T06:02:13.815611Z","steps":["trace[210641523] 'process raft request' (duration: 507.607077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:02:13.815683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:02:13.307793Z","time spent":"507.837481ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:02:13.815889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.128109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:02:13.815933Z","caller":"traceutil/trace.go:171","msg":"trace[1068127446] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:904; }","duration":"117.216351ms","start":"2026-02-06T06:02:13.698705Z","end":"2026-02-06T06:02:13.815921Z","steps":["trace[1068127446] 'agreement among raft nodes before linearized reading' (duration: 117.15276ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:14.640364Z","caller":"traceutil/trace.go:171","msg":"trace[1817963404] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"102.723975ms","start":"2026-02-06T06:02:14.537623Z","end":"2026-02-06T06:02:14.640347Z","steps":["trace[1817963404] 'process raft request' (duration: 102.696044ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:14.640586Z","caller":"traceutil/trace.go:171","msg":"trace[1234412666] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"143.306922ms","start":"2026-02-06T06:02:14.497251Z","end":"2026-02-06T06:02:14.640558Z","steps":["trace[1234412666] 'process raft request' (duration: 82.221669ms)","trace[1234412666] 'compare' (duration: 60.766297ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:02:15.494029Z","caller":"traceutil/trace.go:171","msg":"trace[1469160547] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"266.106521ms","start":"2026-02-06T06:02:15.227903Z","end":"2026-02-06T06:02:15.49401Z","steps":["trace[1469160547] 'read index received' (duration: 265.968108ms)","trace[1469160547] 'applied index is now lower than readState.Index' (duration: 137.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:02:15.494116Z","caller":"traceutil/trace.go:171","msg":"trace[565810993] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"512.65051ms","start":"2026-02-06T06:02:14.98146Z","end":"2026-02-06T06:02:15.49411Z","steps":["trace[565810993] 'process raft request' (duration: 512.459255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:02:15.49424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:02:14.981447Z","time spent":"512.688361ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:02:15.494286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.421596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:02:15.494354Z","caller":"traceutil/trace.go:171","msg":"trace[2077173343] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:917; }","duration":"216.543129ms","start":"2026-02-06T06:02:15.277793Z","end":"2026-02-06T06:02:15.494336Z","steps":["trace[2077173343] 'agreement among raft nodes before linearized reading' (duration: 216.436336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:02:15.494485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.580152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:02:15.494512Z","caller":"traceutil/trace.go:171","msg":"trace[917059557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"266.642314ms","start":"2026-02-06T06:02:15.227862Z","end":"2026-02-06T06:02:15.494504Z","steps":["trace[917059557] 'agreement among raft nodes before linearized reading' (duration: 266.599963ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:16.185343Z","caller":"traceutil/trace.go:171","msg":"trace[784136125] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"153.679398ms","start":"2026-02-06T06:02:16.031645Z","end":"2026-02-06T06:02:16.185324Z","steps":["trace[784136125] 'process raft request' (duration: 153.588376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:02:16.630138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.164019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:02:16.630203Z","caller":"traceutil/trace.go:171","msg":"trace[892813044] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"392.214563ms","start":"2026-02-06T06:02:16.237978Z","end":"2026-02-06T06:02:16.630192Z","steps":["trace[892813044] 'read index received' (duration: 67.932491ms)","trace[892813044] 'applied index is now lower than readState.Index' (duration: 324.281302ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:02:16.630252Z","caller":"traceutil/trace.go:171","msg":"trace[904746493] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"424.108803ms","start":"2026-02-06T06:02:16.206137Z","end":"2026-02-06T06:02:16.630246Z","steps":["trace[904746493] 'process raft request' (duration: 99.78517ms)","trace[904746493] 'compare' (duration: 324.084888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:02:16.630281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:02:16.206129Z","time spent":"424.137843ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:02:16.630401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.367527ms","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-02-06T06:02:16.630446Z","caller":"traceutil/trace.go:171","msg":"trace[2031949225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"127.443879ms","start":"2026-02-06T06:02:16.502991Z","end":"2026-02-06T06:02:16.630435Z","steps":["trace[2031949225] 'agreement among raft nodes before linearized reading' (duration: 127.326976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:02:16.630512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.532459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:02:16.63053Z","caller":"traceutil/trace.go:171","msg":"trace[2065092851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"392.56713ms","start":"2026-02-06T06:02:16.237957Z","end":"2026-02-06T06:02:16.630524Z","steps":["trace[2065092851] 'agreement among raft nodes before linearized reading' (duration: 392.536519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:02:16.630544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:02:16.237948Z","time spent":"392.59287ms","remote":"127.0.0.1:59940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T06:02:17.047413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.951742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-06T06:02:17.04749Z","caller":"traceutil/trace.go:171","msg":"trace[293805768] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"111.240659ms","start":"2026-02-06T06:02:16.936232Z","end":"2026-02-06T06:02:17.047473Z","steps":["trace[293805768] 'read index received' (duration: 44.361µs)","trace[293805768] 'applied index is now lower than readState.Index' (duration: 111.195128ms)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:02:17.0475Z","caller":"traceutil/trace.go:171","msg":"trace[1516420738] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"331.294793ms","start":"2026-02-06T06:02:16.716187Z","end":"2026-02-06T06:02:17.047482Z","steps":["trace[1516420738] 'process raft request' (duration: 208.211338ms)","trace[1516420738] 'compare' (duration: 122.839529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-06T06:02:17.047572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.343241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T06:02:17.047591Z","caller":"traceutil/trace.go:171","msg":"trace[1775061950] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:921; }","duration":"111.388142ms","start":"2026-02-06T06:02:16.936197Z","end":"2026-02-06T06:02:17.047585Z","steps":["trace[1775061950] 'agreement among raft nodes before linearized reading' (duration: 111.31242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:02:17.047593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:02:16.716172Z","time spent":"331.369425ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-06T06:02:22.726772Z","caller":"traceutil/trace.go:171","msg":"trace[1204713528] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"487.590707ms","start":"2026-02-06T06:02:22.239161Z","end":"2026-02-06T06:02:22.726752Z","steps":["trace[1204713528] 'process raft request' (duration: 487.180279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:02:22.726916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:02:22.239149Z","time spent":"487.689629ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-06T06:02:27.497702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.812567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:02:27.497799Z","caller":"traceutil/trace.go:171","msg":"trace[966312926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"266.96038ms","start":"2026-02-06T06:02:27.230824Z","end":"2026-02-06T06:02:27.497784Z","steps":["trace[966312926] 'range keys from in-memory index tree' (duration: 266.753376ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:02:27.769369Z","caller":"traceutil/trace.go:171","msg":"trace[702205596] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"114.900808ms","start":"2026-02-06T06:02:27.654448Z","end":"2026-02-06T06:02:27.769349Z","steps":["trace[702205596] 'process raft request' (duration: 114.794036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:05:49.924136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.037456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:05:49.924533Z","caller":"traceutil/trace.go:171","msg":"trace[592221133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"692.463834ms","start":"2026-02-06T06:05:49.232058Z","end":"2026-02-06T06:05:49.924521Z","steps":["trace[592221133] 'range keys from in-memory index tree' (duration: 691.958515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:05:49.924567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:05:49.232043Z","time spent":"692.516916ms","remote":"127.0.0.1:59940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-06T06:05:49.924707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.648823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-06T06:05:49.924777Z","caller":"traceutil/trace.go:171","msg":"trace[907912704] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1856; }","duration":"596.749596ms","start":"2026-02-06T06:05:49.32802Z","end":"2026-02-06T06:05:49.924769Z","steps":["trace[907912704] 'range keys from in-memory index tree' (duration: 596.570372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:05:49.92482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:05:49.327985Z","time spent":"596.814596ms","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-06T06:05:49.92498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.209263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-06T06:05:49.925019Z","caller":"traceutil/trace.go:171","msg":"trace[2013581883] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1856; }","duration":"464.272464ms","start":"2026-02-06T06:05:49.460738Z","end":"2026-02-06T06:05:49.925011Z","steps":["trace[2013581883] 'count revisions from in-memory index tree' (duration: 464.171852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:05:49.925047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T06:05:49.460725Z","time spent":"464.314725ms","remote":"127.0.0.1:60366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-06T06:05:49.925282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.965754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-06T06:05:49.925564Z","caller":"traceutil/trace.go:171","msg":"trace[2047756859] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1856; }","duration":"288.277639ms","start":"2026-02-06T06:05:49.63728Z","end":"2026-02-06T06:05:49.925558Z","steps":["trace[2047756859] 'count revisions from in-memory index tree' (duration: 287.909263ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:06:21.30377Z","caller":"traceutil/trace.go:171","msg":"trace[632410270] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"117.066113ms","start":"2026-02-06T06:06:21.186681Z","end":"2026-02-06T06:06:21.303748Z","steps":["trace[632410270] 'read index received' (duration: 116.92058ms)","trace[632410270] 'applied index is now lower than readState.Index' (duration: 144.643µs)"],"step_count":2} {"level":"info","ts":"2026-02-06T06:06:21.303889Z","caller":"traceutil/trace.go:171","msg":"trace[1354024236] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"188.143653ms","start":"2026-02-06T06:06:21.115734Z","end":"2026-02-06T06:06:21.303877Z","steps":["trace[1354024236] 'process raft request' (duration: 187.865557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:06:21.304058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.359899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-06T06:06:21.304091Z","caller":"traceutil/trace.go:171","msg":"trace[369834400] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1993; }","duration":"117.436421ms","start":"2026-02-06T06:06:21.186645Z","end":"2026-02-06T06:06:21.304082Z","steps":["trace[369834400] 'agreement among raft nodes before linearized reading' (duration: 117.362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-06T06:06:21.30432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.134426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-06T06:06:21.30436Z","caller":"traceutil/trace.go:171","msg":"trace[1412348725] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:1993; }","duration":"107.204938ms","start":"2026-02-06T06:06:21.197143Z","end":"2026-02-06T06:06:21.304348Z","steps":["trace[1412348725] 'agreement among raft nodes before linearized reading' (duration: 107.133817ms)"],"step_count":1} {"level":"info","ts":"2026-02-06T06:06:21.440147Z","caller":"traceutil/trace.go:171","msg":"trace[315979529] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"113.418433ms","start":"2026-02-06T06:06:21.326705Z","end":"2026-02-06T06:06:21.440123Z","steps":["trace[315979529] 'process raft request' (duration: 113.121527ms)"],"step_count":1}