{"level":"warn","ts":"2026-06-24T17:07:08.694966Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T17:07:08.695094Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.209: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.19.213.209:2380","--initial-cluster=instance=https://199.19.213.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.209: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-06-24T17:07:08.695224Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T17:07:08.695249Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.209:2380"]} {"level":"info","ts":"2026-06-24T17:07:08.695283Z","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-06-24T17:07:08.696572Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"]} {"level":"info","ts":"2026-06-24T17:07:08.696736Z","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.19.213.209:2380"],"listen-peer-urls":["https://199.19.213.209:2380"],"advertise-client-urls":["https://199.19.213.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.209: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-06-24T17:07:08.701409Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.358786ms"} {"level":"info","ts":"2026-06-24T17:07:08.726223Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9409e054be1d0d72","cluster-id":"ff5f9c0688884e7d"} {"level":"info","ts":"2026-06-24T17:07:08.726308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=()"} {"level":"info","ts":"2026-06-24T17:07:08.726333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became follower at term 0"} {"level":"info","ts":"2026-06-24T17:07:08.726344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9409e054be1d0d72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-24T17:07:08.726352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became follower at term 1"} {"level":"info","ts":"2026-06-24T17:07:08.726385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=(10667303846975180146)"} {"level":"warn","ts":"2026-06-24T17:07:08.873953Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-24T17:07:08.897175Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-24T17:07:08.900653Z","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-06-24T17:07:08.905597Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9409e054be1d0d72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-24T17:07:08.905795Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9409e054be1d0d72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-24T17:07:08.906138Z","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-06-24T17:07:08.906233Z","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-06-24T17:07:08.906356Z","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-06-24T17:07:08.917237Z","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-06-24T17:07:08.917472Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.209:2380"} {"level":"info","ts":"2026-06-24T17:07:08.917495Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.209:2380"} {"level":"info","ts":"2026-06-24T17:07:08.917736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9409e054be1d0d72","initial-advertise-peer-urls":["https://199.19.213.209:2380"],"listen-peer-urls":["https://199.19.213.209:2380"],"advertise-client-urls":["https://199.19.213.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-24T17:07:08.917778Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-24T17:07:08.927403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=(10667303846975180146)"} {"level":"info","ts":"2026-06-24T17:07:08.927492Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff5f9c0688884e7d","local-member-id":"9409e054be1d0d72","added-peer-id":"9409e054be1d0d72","added-peer-peer-urls":["https://199.19.213.209:2380"]} {"level":"info","ts":"2026-06-24T17:07:09.72711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 is starting a new election at term 1"} {"level":"info","ts":"2026-06-24T17:07:09.727366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-24T17:07:09.727471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 received MsgPreVoteResp from 9409e054be1d0d72 at term 1"} {"level":"info","ts":"2026-06-24T17:07:09.727586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became candidate at term 2"} {"level":"info","ts":"2026-06-24T17:07:09.727634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 received MsgVoteResp from 9409e054be1d0d72 at term 2"} {"level":"info","ts":"2026-06-24T17:07:09.727687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became leader at term 2"} {"level":"info","ts":"2026-06-24T17:07:09.727744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9409e054be1d0d72 elected leader 9409e054be1d0d72 at term 2"} {"level":"info","ts":"2026-06-24T17:07:09.728722Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:07:09.729451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9409e054be1d0d72","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.209:2379]}","request-path":"/0/members/9409e054be1d0d72/attributes","cluster-id":"ff5f9c0688884e7d","publish-timeout":"7s"} {"level":"info","ts":"2026-06-24T17:07:09.729619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T17:07:09.730274Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff5f9c0688884e7d","local-member-id":"9409e054be1d0d72","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:07:09.730437Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:07:09.730533Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:07:09.731012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T17:07:09.733626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-24T17:07:09.733814Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-24T17:07:09.735906Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-24T17:07:09.737609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.209:2379"} {"level":"info","ts":"2026-06-24T17:07:13.567115Z","caller":"traceutil/trace.go:171","msg":"trace[1080729773] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"107.172664ms","start":"2026-06-24T17:07:13.459899Z","end":"2026-06-24T17:07:13.567072Z","steps":["trace[1080729773] 'process raft request' (duration: 23.966516ms)","trace[1080729773] 'compare' (duration: 83.041196ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:07:16.398135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.781064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:07:16.398332Z","caller":"traceutil/trace.go:171","msg":"trace[1139864623] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"976.060216ms","start":"2026-06-24T17:07:15.422251Z","end":"2026-06-24T17:07:16.398312Z","steps":["trace[1139864623] 'read index received' (duration: 313.020792ms)","trace[1139864623] 'applied index is now lower than readState.Index' (duration: 663.037844ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:07:16.398464Z","caller":"traceutil/trace.go:171","msg":"trace[1755199750] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"1.04581898s","start":"2026-06-24T17:07:15.352621Z","end":"2026-06-24T17:07:16.39844Z","steps":["trace[1755199750] 'process raft request' (duration: 382.764786ms)","trace[1755199750] 'compare' (duration: 661.643923ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:07:16.39858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:15.352606Z","time spent":"1.045911332s","remote":"127.0.0.1:45972","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-06-24T17:07:16.39893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.666252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:07:16.399032Z","caller":"traceutil/trace.go:171","msg":"trace[141105409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:213; }","duration":"976.799964ms","start":"2026-06-24T17:07:15.422215Z","end":"2026-06-24T17:07:16.399015Z","steps":["trace[141105409] 'agreement among raft nodes before linearized reading' (duration: 976.663223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:07:16.39912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:15.422199Z","time spent":"976.891634ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T17:07:17.046652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.287662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:07:17.047187Z","caller":"traceutil/trace.go:171","msg":"trace[1253059563] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"914.971538ms","start":"2026-06-24T17:07:16.131788Z","end":"2026-06-24T17:07:17.04676Z","steps":["trace[1253059563] 'process raft request' (duration: 583.513335ms)","trace[1253059563] 'compare' (duration: 331.108011ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:07:17.047279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:16.131765Z","time spent":"915.482593ms","remote":"127.0.0.1:45892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T17:07:17.107694Z","caller":"traceutil/trace.go:171","msg":"trace[900446001] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"967.745343ms","start":"2026-06-24T17:07:16.139918Z","end":"2026-06-24T17:07:17.107663Z","steps":["trace[900446001] 'process raft request' (duration: 967.47355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:07:17.10779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:16.349473Z","time spent":"758.306493ms","remote":"127.0.0.1:45760","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-24T17:07:17.10783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:16.139904Z","time spent":"967.866034ms","remote":"127.0.0.1:45892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T17:07:17.107851Z","caller":"traceutil/trace.go:171","msg":"trace[1548493986] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"704.62755ms","start":"2026-06-24T17:07:16.403173Z","end":"2026-06-24T17:07:17.107801Z","steps":["trace[1548493986] 'process raft request' (duration: 704.515929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:07:17.107942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139282527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:07:17.108027Z","caller":"traceutil/trace.go:171","msg":"trace[1917295220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:216; }","duration":"1.139422548s","start":"2026-06-24T17:07:15.968583Z","end":"2026-06-24T17:07:17.108005Z","steps":["trace[1917295220] 'agreement among raft nodes before linearized reading' (duration: 1.139233736s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:07:17.108043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:16.403142Z","time spent":"704.791602ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T17:07:17.108068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:15.968563Z","time spent":"1.139496968s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T17:07:17.10819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613585084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-06-24T17:07:17.108302Z","caller":"traceutil/trace.go:171","msg":"trace[1870065649] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:216; }","duration":"1.613766105s","start":"2026-06-24T17:07:15.494516Z","end":"2026-06-24T17:07:17.108283Z","steps":["trace[1870065649] 'agreement among raft nodes before linearized reading' (duration: 1.613577143s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:07:17.108411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:15.494497Z","time spent":"1.613905187s","remote":"127.0.0.1:45882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3733,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-24T17:07:17.108578Z","caller":"traceutil/trace.go:171","msg":"trace[1110398783] linearizableReadLoop","detail":"{readStateIndex:221; appliedIndex:218; }","duration":"709.388029ms","start":"2026-06-24T17:07:16.398365Z","end":"2026-06-24T17:07:17.107753Z","steps":["trace[1110398783] 'read index received' (duration: 317.071087ms)","trace[1110398783] 'applied index is now lower than readState.Index' (duration: 392.315762ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:07:17.108721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.85393ms","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-06-24T17:07:17.108757Z","caller":"traceutil/trace.go:171","msg":"trace[1540836244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:216; }","duration":"369.93074ms","start":"2026-06-24T17:07:16.738815Z","end":"2026-06-24T17:07:17.108745Z","steps":["trace[1540836244] 'agreement among raft nodes before linearized reading' (duration: 369.85797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:07:17.108785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:07:16.738796Z","time spent":"369.982171ms","remote":"127.0.0.1:45972","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\" "} 2026/06/24 17:07:17 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-06-24T17:07:18.707739Z","caller":"traceutil/trace.go:171","msg":"trace[1582658353] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"120.435435ms","start":"2026-06-24T17:07:18.587272Z","end":"2026-06-24T17:07:18.707707Z","steps":["trace[1582658353] 'process raft request' (duration: 120.227763ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:07:18.707834Z","caller":"traceutil/trace.go:171","msg":"trace[1189203400] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"120.104103ms","start":"2026-06-24T17:07:18.587692Z","end":"2026-06-24T17:07:18.707796Z","steps":["trace[1189203400] 'process raft request' (duration: 119.899171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:03.962188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969011668993522525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:08:04.291468Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.01691772s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T17:08:04.296702Z","caller":"traceutil/trace.go:171","msg":"trace[760831548] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"835.566919ms","start":"2026-06-24T17:08:03.461076Z","end":"2026-06-24T17:08:04.296643Z","steps":["trace[760831548] 'read index received' (duration: 830.542921ms)","trace[760831548] 'applied index is now lower than readState.Index' (duration: 5.023018ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:08:04.296768Z","caller":"traceutil/trace.go:171","msg":"trace[80014053] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.023626951s","start":"2026-06-24T17:08:03.273113Z","end":"2026-06-24T17:08:04.29674Z","steps":["trace[80014053] 'process raft request' (duration: 1.019003136s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.296882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:03.273088Z","time spent":"1.023724301s","remote":"127.0.0.1:45780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:08:04.297019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.10807ms","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-06-24T17:08:04.297082Z","caller":"traceutil/trace.go:171","msg":"trace[454777788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"833.280701ms","start":"2026-06-24T17:08:03.463789Z","end":"2026-06-24T17:08:04.29707Z","steps":["trace[454777788] 'agreement among raft nodes before linearized reading' (duration: 833.038699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.297116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.585193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T17:08:04.297175Z","caller":"traceutil/trace.go:171","msg":"trace[200276436] range","detail":"{range_begin:/registry/masterleases/199.19.213.209; range_end:; response_count:1; response_revision:647; }","duration":"173.636423ms","start":"2026-06-24T17:08:04.123519Z","end":"2026-06-24T17:08:04.297155Z","steps":["trace[200276436] 'agreement among raft nodes before linearized reading' (duration: 173.558883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.297122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:03.463771Z","time spent":"833.342261ms","remote":"127.0.0.1:45972","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-06-24T17:08:04.297225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.365762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-24T17:08:04.29731Z","caller":"traceutil/trace.go:171","msg":"trace[693466116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"833.488182ms","start":"2026-06-24T17:08:03.463801Z","end":"2026-06-24T17:08:04.29729Z","steps":["trace[693466116] 'agreement among raft nodes before linearized reading' (duration: 833.299651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.297361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:03.463754Z","time spent":"833.597313ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T17:08:04.297008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.629031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:08:04.297592Z","caller":"traceutil/trace.go:171","msg":"trace[1953050026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"328.272815ms","start":"2026-06-24T17:08:03.969309Z","end":"2026-06-24T17:08:04.297581Z","steps":["trace[1953050026] 'agreement among raft nodes before linearized reading' (duration: 327.579331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.297625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:03.969289Z","time spent":"328.327456ms","remote":"127.0.0.1:45716","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-06-24T17:08:04.297571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.483135ms","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-06-24T17:08:04.297899Z","caller":"traceutil/trace.go:171","msg":"trace[1383018943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"836.808468ms","start":"2026-06-24T17:08:03.461069Z","end":"2026-06-24T17:08:04.297877Z","steps":["trace[1383018943] 'agreement among raft nodes before linearized reading' (duration: 835.980762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.29796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.834236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6trlj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-24T17:08:04.29801Z","caller":"traceutil/trace.go:171","msg":"trace[1571787326] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6trlj; range_end:; response_count:1; response_revision:647; }","duration":"593.915536ms","start":"2026-06-24T17:08:03.704081Z","end":"2026-06-24T17:08:04.297996Z","steps":["trace[1571787326] 'agreement among raft nodes before linearized reading' (duration: 592.96446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.298039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:03.704063Z","time spent":"593.968527ms","remote":"127.0.0.1:45892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-6trlj\" "} {"level":"warn","ts":"2026-06-24T17:08:04.298184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:03.460994Z","time spent":"836.990048ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-24T17:08:04.425905Z","caller":"traceutil/trace.go:171","msg":"trace[1680506904] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"124.257146ms","start":"2026-06-24T17:08:04.301628Z","end":"2026-06-24T17:08:04.425885Z","steps":["trace[1680506904] 'process raft request' (duration: 124.189485ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:08:04.425955Z","caller":"traceutil/trace.go:171","msg":"trace[1595722284] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"124.71561ms","start":"2026-06-24T17:08:04.301201Z","end":"2026-06-24T17:08:04.425917Z","steps":["trace[1595722284] 'process raft request' (duration: 124.497428ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:08:04.426407Z","caller":"traceutil/trace.go:171","msg":"trace[1824784310] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"123.835482ms","start":"2026-06-24T17:08:04.302561Z","end":"2026-06-24T17:08:04.426397Z","steps":["trace[1824784310] 'process raft request' (duration: 123.294728ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:08:04.426597Z","caller":"traceutil/trace.go:171","msg":"trace[2037130856] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"118.12391ms","start":"2026-06-24T17:08:04.308393Z","end":"2026-06-24T17:08:04.426517Z","steps":["trace[2037130856] 'process raft request' (duration: 117.898308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.761883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.684813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T17:08:04.762254Z","caller":"traceutil/trace.go:171","msg":"trace[466427186] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"444.692883ms","start":"2026-06-24T17:08:04.317547Z","end":"2026-06-24T17:08:04.76224Z","steps":["trace[466427186] 'process raft request' (duration: 336.552107ms)","trace[466427186] 'compare' (duration: 107.462852ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:08:04.762287Z","caller":"traceutil/trace.go:171","msg":"trace[1967480152] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"410.239935ms","start":"2026-06-24T17:08:04.352026Z","end":"2026-06-24T17:08:04.762266Z","steps":["trace[1967480152] 'process raft request' (duration: 410.000894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:04.762309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:04.317535Z","time spent":"444.748814ms","remote":"127.0.0.1:45724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:08:04.762379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:04.351981Z","time spent":"410.352287ms","remote":"127.0.0.1:46196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T17:08:05.94161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.62998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:08:05.941683Z","caller":"traceutil/trace.go:171","msg":"trace[376405008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"972.745272ms","start":"2026-06-24T17:08:04.968922Z","end":"2026-06-24T17:08:05.941667Z","steps":["trace[376405008] 'range keys from in-memory index tree' (duration: 972.52777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:05.941731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:04.968892Z","time spent":"972.830942ms","remote":"127.0.0.1:45700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T17:08:05.941954Z","caller":"traceutil/trace.go:171","msg":"trace[2041683375] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"790.020892ms","start":"2026-06-24T17:08:05.15192Z","end":"2026-06-24T17:08:05.941941Z","steps":["trace[2041683375] 'process raft request' (duration: 789.563878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:05.942028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:05.151888Z","time spent":"790.097002ms","remote":"127.0.0.1:45780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:08:18.920286Z","caller":"traceutil/trace.go:171","msg":"trace[183390686] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"115.682074ms","start":"2026-06-24T17:08:18.804577Z","end":"2026-06-24T17:08:18.920259Z","steps":["trace[183390686] 'process raft request' (duration: 115.198066ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:08:21.727988Z","caller":"traceutil/trace.go:171","msg":"trace[230629336] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"110.462901ms","start":"2026-06-24T17:08:21.617507Z","end":"2026-06-24T17:08:21.72797Z","steps":["trace[230629336] 'read index received' (duration: 110.345858ms)","trace[230629336] 'applied index is now lower than readState.Index' (duration: 116.303µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:08:21.728257Z","caller":"traceutil/trace.go:171","msg":"trace[1909377429] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"308.048027ms","start":"2026-06-24T17:08:21.420197Z","end":"2026-06-24T17:08:21.728245Z","steps":["trace[1909377429] 'process raft request' (duration: 307.660445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:21.728357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:08:21.420175Z","time spent":"308.115055ms","remote":"127.0.0.1:45972","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-06-24T17:08:21.728495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.981959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:08:21.728532Z","caller":"traceutil/trace.go:171","msg":"trace[1073769434] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:820; }","duration":"111.044916ms","start":"2026-06-24T17:08:21.617477Z","end":"2026-06-24T17:08:21.728522Z","steps":["trace[1073769434] 'agreement among raft nodes before linearized reading' (duration: 110.98666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:21.728666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.050266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:08:21.728708Z","caller":"traceutil/trace.go:171","msg":"trace[1422664422] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:820; }","duration":"111.134985ms","start":"2026-06-24T17:08:21.617563Z","end":"2026-06-24T17:08:21.728698Z","steps":["trace[1422664422] 'agreement among raft nodes before linearized reading' (duration: 111.071538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:21.729095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.048016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:08:21.729144Z","caller":"traceutil/trace.go:171","msg":"trace[124626239] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:820; }","duration":"111.118124ms","start":"2026-06-24T17:08:21.618018Z","end":"2026-06-24T17:08:21.729137Z","steps":["trace[124626239] 'agreement among raft nodes before linearized reading' (duration: 111.054917ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:08:50.296928Z","caller":"traceutil/trace.go:171","msg":"trace[457847931] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"230.458528ms","start":"2026-06-24T17:08:50.066448Z","end":"2026-06-24T17:08:50.296906Z","steps":["trace[457847931] 'read index received' (duration: 192.017664ms)","trace[457847931] 'applied index is now lower than readState.Index' (duration: 38.439724ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:08:50.297221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.695302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:08:50.297363Z","caller":"traceutil/trace.go:171","msg":"trace[234293149] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1034; }","duration":"230.936836ms","start":"2026-06-24T17:08:50.066409Z","end":"2026-06-24T17:08:50.297346Z","steps":["trace[234293149] 'agreement among raft nodes before linearized reading' (duration: 230.566375ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:08:50.297565Z","caller":"traceutil/trace.go:171","msg":"trace[1213563887] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"229.332544ms","start":"2026-06-24T17:08:50.06822Z","end":"2026-06-24T17:08:50.297553Z","steps":["trace[1213563887] 'process raft request' (duration: 229.177195ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:08:50.297852Z","caller":"traceutil/trace.go:171","msg":"trace[1116919876] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"137.07676ms","start":"2026-06-24T17:08:50.16075Z","end":"2026-06-24T17:08:50.297827Z","steps":["trace[1116919876] 'process raft request' (duration: 136.749421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:50.298045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.884605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-24T17:08:50.298118Z","caller":"traceutil/trace.go:171","msg":"trace[475969893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"229.990391ms","start":"2026-06-24T17:08:50.068116Z","end":"2026-06-24T17:08:50.298106Z","steps":["trace[475969893] 'agreement among raft nodes before linearized reading' (duration: 229.754988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:08:50.298035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.227905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T17:08:50.29819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.633835ms","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-06-24T17:08:50.298229Z","caller":"traceutil/trace.go:171","msg":"trace[1713397697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1037; }","duration":"228.693758ms","start":"2026-06-24T17:08:50.069528Z","end":"2026-06-24T17:08:50.298221Z","steps":["trace[1713397697] 'agreement among raft nodes before linearized reading' (duration: 228.577871ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:08:50.29824Z","caller":"traceutil/trace.go:171","msg":"trace[1013577536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"227.443777ms","start":"2026-06-24T17:08:50.070776Z","end":"2026-06-24T17:08:50.29822Z","steps":["trace[1013577536] 'agreement among raft nodes before linearized reading' (duration: 227.205223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:06.874662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969011668993523504,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:09:07.374816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969011668993523504,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:09:07.828864Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.471264351s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T17:09:07.829983Z","caller":"traceutil/trace.go:171","msg":"trace[152547515] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"1.456155691s","start":"2026-06-24T17:09:06.373812Z","end":"2026-06-24T17:09:07.829968Z","steps":["trace[152547515] 'read index received' (duration: 1.455945473s)","trace[152547515] 'applied index is now lower than readState.Index' (duration: 209.688µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:09:07.830229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011421353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T17:09:07.830291Z","caller":"traceutil/trace.go:171","msg":"trace[1687228296] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1094; }","duration":"1.011514357s","start":"2026-06-24T17:09:06.818764Z","end":"2026-06-24T17:09:07.830278Z","steps":["trace[1687228296] 'agreement among raft nodes before linearized reading' (duration: 1.011413833s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.830326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.818757Z","time spent":"1.011561079s","remote":"127.0.0.1:46122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:09:07.830317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452426992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-24T17:09:07.830366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131217392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-24T17:09:07.830397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072135792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-24T17:09:07.830458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224629964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-06-24T17:09:07.830452Z","caller":"traceutil/trace.go:171","msg":"trace[870843883] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1094; }","duration":"1.131351937s","start":"2026-06-24T17:09:06.699082Z","end":"2026-06-24T17:09:07.830434Z","steps":["trace[870843883] 'agreement among raft nodes before linearized reading' (duration: 1.13117961s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.830435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.418733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-24T17:09:07.830492Z","caller":"traceutil/trace.go:171","msg":"trace[902862355] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1094; }","duration":"1.224694227s","start":"2026-06-24T17:09:06.605789Z","end":"2026-06-24T17:09:07.830483Z","steps":["trace[902862355] 'agreement among raft nodes before linearized reading' (duration: 1.224638594s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.830508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.573259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T17:09:07.830509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.699065Z","time spent":"1.13143304s","remote":"127.0.0.1:46068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:09:07.830526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.605763Z","time spent":"1.224753209s","remote":"127.0.0.1:45796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":1108,"request content":"key:\"/registry/configmaps/kube-system/kubeadm-config\" "} {"level":"info","ts":"2026-06-24T17:09:07.830526Z","caller":"traceutil/trace.go:171","msg":"trace[1431794589] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1094; }","duration":"998.526387ms","start":"2026-06-24T17:09:06.831981Z","end":"2026-06-24T17:09:07.830508Z","steps":["trace[1431794589] 'agreement among raft nodes before linearized reading' (duration: 998.328939ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:09:07.83054Z","caller":"traceutil/trace.go:171","msg":"trace[129428729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"503.650112ms","start":"2026-06-24T17:09:07.326881Z","end":"2026-06-24T17:09:07.830531Z","steps":["trace[129428729] 'agreement among raft nodes before linearized reading' (duration: 503.60944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.830571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.831915Z","time spent":"998.645152ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T17:09:07.830572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:07.326847Z","time spent":"503.718435ms","remote":"127.0.0.1:45700","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-06-24T17:09:07.830585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.949546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-24T17:09:07.830837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45635495s","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":"warn","ts":"2026-06-24T17:09:07.830317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079389158s","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-06-24T17:09:07.83091Z","caller":"traceutil/trace.go:171","msg":"trace[841058819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"1.080025966s","start":"2026-06-24T17:09:06.750876Z","end":"2026-06-24T17:09:07.830902Z","steps":["trace[841058819] 'agreement among raft nodes before linearized reading' (duration: 1.079310595s)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:09:07.830467Z","caller":"traceutil/trace.go:171","msg":"trace[1331255290] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1094; }","duration":"1.072212594s","start":"2026-06-24T17:09:06.75824Z","end":"2026-06-24T17:09:07.830453Z","steps":["trace[1331255290] 'agreement among raft nodes before linearized reading' (duration: 1.072124751s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.830973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.750861Z","time spent":"1.080076868s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-24T17:09:07.830848Z","caller":"traceutil/trace.go:171","msg":"trace[987345175] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"1.472614677s","start":"2026-06-24T17:09:06.357457Z","end":"2026-06-24T17:09:07.830071Z","steps":["trace[987345175] 'process raft request' (duration: 1.472402588s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.83101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.758233Z","time spent":"1.072723186s","remote":"127.0.0.1:46138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-06-24T17:09:07.8304Z","caller":"traceutil/trace.go:171","msg":"trace[415191529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1094; }","duration":"1.452549228s","start":"2026-06-24T17:09:06.377835Z","end":"2026-06-24T17:09:07.830384Z","steps":["trace[415191529] 'agreement among raft nodes before linearized reading' (duration: 1.452303027s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.832069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.377825Z","time spent":"1.454232769s","remote":"127.0.0.1:45972","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-06-24T17:09:07.832214Z","caller":"traceutil/trace.go:171","msg":"trace[141060912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1094; }","duration":"1.458379794s","start":"2026-06-24T17:09:06.373766Z","end":"2026-06-24T17:09:07.830875Z","steps":["trace[141060912] 'agreement among raft nodes before linearized reading' (duration: 1.456283897s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.831168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.357421Z","time spent":"1.473669792s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:09:07.830789Z","caller":"traceutil/trace.go:171","msg":"trace[1139204037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"969.28806ms","start":"2026-06-24T17:09:06.861459Z","end":"2026-06-24T17:09:07.830747Z","steps":["trace[1139204037] 'agreement among raft nodes before linearized reading' (duration: 968.779689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:07.832506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.861395Z","time spent":"971.047394ms","remote":"127.0.0.1:45972","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-06-24T17:09:07.832676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:06.373746Z","time spent":"1.458631985s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-24T17:09:07.974784Z","caller":"traceutil/trace.go:171","msg":"trace[1532224857] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"137.405751ms","start":"2026-06-24T17:09:07.837362Z","end":"2026-06-24T17:09:07.974768Z","steps":["trace[1532224857] 'process raft request' (duration: 136.994204ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:09:07.975032Z","caller":"traceutil/trace.go:171","msg":"trace[632499761] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"137.488995ms","start":"2026-06-24T17:09:07.837508Z","end":"2026-06-24T17:09:07.974997Z","steps":["trace[632499761] 'process raft request' (duration: 137.231034ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:09:07.97519Z","caller":"traceutil/trace.go:171","msg":"trace[1993525432] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"136.781696ms","start":"2026-06-24T17:09:07.838398Z","end":"2026-06-24T17:09:07.97518Z","steps":["trace[1993525432] 'process raft request' (duration: 136.531246ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:09:07.975239Z","caller":"traceutil/trace.go:171","msg":"trace[1293902223] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"136.463182ms","start":"2026-06-24T17:09:07.838766Z","end":"2026-06-24T17:09:07.975229Z","steps":["trace[1293902223] 'process raft request' (duration: 136.291645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:14.405639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969011668993523551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:09:14.906535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969011668993523551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:09:15.051473Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172131533s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T17:09:15.051923Z","caller":"traceutil/trace.go:171","msg":"trace[1820028469] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"1.172657842s","start":"2026-06-24T17:09:13.879246Z","end":"2026-06-24T17:09:15.051904Z","steps":["trace[1820028469] 'process raft request' (duration: 1.172525217s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.052052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:13.879228Z","time spent":"1.172755606s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:09:15.527818Z","caller":"traceutil/trace.go:171","msg":"trace[868371301] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1156; }","duration":"1.622719256s","start":"2026-06-24T17:09:13.90507Z","end":"2026-06-24T17:09:15.527789Z","steps":["trace[868371301] 'read index received' (duration: 1.146735206s)","trace[868371301] 'applied index is now lower than readState.Index' (duration: 475.98165ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:09:15.527962Z","caller":"traceutil/trace.go:171","msg":"trace[847712612] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"870.996667ms","start":"2026-06-24T17:09:14.656954Z","end":"2026-06-24T17:09:15.527951Z","steps":["trace[847712612] 'process raft request' (duration: 870.647904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.52809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:14.656932Z","time spent":"871.07327ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:09:15.528179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561100623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:09:15.528211Z","caller":"traceutil/trace.go:171","msg":"trace[444914961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"1.561157994s","start":"2026-06-24T17:09:13.967046Z","end":"2026-06-24T17:09:15.528204Z","steps":["trace[444914961] 'agreement among raft nodes before linearized reading' (duration: 1.561099132s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.52823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:13.96703Z","time spent":"1.561196556s","remote":"127.0.0.1:45700","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-06-24T17:09:15.528378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.623309827s","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-06-24T17:09:15.528399Z","caller":"traceutil/trace.go:171","msg":"trace[1991077111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1114; }","duration":"1.623356509s","start":"2026-06-24T17:09:13.905037Z","end":"2026-06-24T17:09:15.528394Z","steps":["trace[1991077111] 'agreement among raft nodes before linearized reading' (duration: 1.623274776s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.528416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:13.905027Z","time spent":"1.623384861s","remote":"127.0.0.1:45972","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":"warn","ts":"2026-06-24T17:09:15.528423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452939976s","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-06-24T17:09:15.528445Z","caller":"traceutil/trace.go:171","msg":"trace[152820190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"1.452974188s","start":"2026-06-24T17:09:14.075464Z","end":"2026-06-24T17:09:15.528439Z","steps":["trace[152820190] 'agreement among raft nodes before linearized reading' (duration: 1.452893295s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.528468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:14.075457Z","time spent":"1.453006649s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T17:09:15.528513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454486154s","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-06-24T17:09:15.528532Z","caller":"traceutil/trace.go:171","msg":"trace[447446819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1114; }","duration":"1.454540136s","start":"2026-06-24T17:09:14.073987Z","end":"2026-06-24T17:09:15.528527Z","steps":["trace[447446819] 'agreement among raft nodes before linearized reading' (duration: 1.454496634s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.528547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:14.073963Z","time spent":"1.454578827s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T17:09:15.528586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.600849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T17:09:15.528611Z","caller":"traceutil/trace.go:171","msg":"trace[929672367] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1114; }","duration":"487.672842ms","start":"2026-06-24T17:09:15.040931Z","end":"2026-06-24T17:09:15.528604Z","steps":["trace[929672367] 'agreement among raft nodes before linearized reading' (duration: 487.63016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.52863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:15.040916Z","time spent":"487.709423ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:09:15.528669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458256985s","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-06-24T17:09:15.528687Z","caller":"traceutil/trace.go:171","msg":"trace[1561102444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1114; }","duration":"1.458310688s","start":"2026-06-24T17:09:14.070371Z","end":"2026-06-24T17:09:15.528681Z","steps":["trace[1561102444] 'agreement among raft nodes before linearized reading' (duration: 1.458260046s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.528702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:14.070354Z","time spent":"1.458344938s","remote":"127.0.0.1:45972","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-06-24T17:09:15.528778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402838037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T17:09:15.528801Z","caller":"traceutil/trace.go:171","msg":"trace[903307760] range","detail":"{range_begin:/registry/masterleases/199.19.213.209; range_end:; response_count:1; response_revision:1114; }","duration":"1.40290442s","start":"2026-06-24T17:09:14.12589Z","end":"2026-06-24T17:09:15.528795Z","steps":["trace[903307760] 'agreement among raft nodes before linearized reading' (duration: 1.402813466s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.528819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:14.125885Z","time spent":"1.40292915s","remote":"127.0.0.1:45724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.209\" "} {"level":"warn","ts":"2026-06-24T17:09:15.528889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452946696s","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-06-24T17:09:15.528912Z","caller":"traceutil/trace.go:171","msg":"trace[1839545994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1114; }","duration":"1.452981757s","start":"2026-06-24T17:09:14.075925Z","end":"2026-06-24T17:09:15.528907Z","steps":["trace[1839545994] 'agreement among raft nodes before linearized reading' (duration: 1.452910924s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:15.528925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:14.075919Z","time spent":"1.453002888s","remote":"127.0.0.1:45972","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-06-24T17:09:18.054479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969011668993523574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:09:18.554646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969011668993523574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:09:19.05505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969011668993523574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T17:09:19.055707Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.515846663s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T17:09:19.056002Z","caller":"traceutil/trace.go:171","msg":"trace[1913540448] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"1.501919311s","start":"2026-06-24T17:09:17.554061Z","end":"2026-06-24T17:09:19.05598Z","steps":["trace[1913540448] 'read index received' (duration: 1.501762475s)","trace[1913540448] 'applied index is now lower than readState.Index' (duration: 156.126µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:09:19.056122Z","caller":"traceutil/trace.go:171","msg":"trace[1655991721] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"1.516299519s","start":"2026-06-24T17:09:17.539789Z","end":"2026-06-24T17:09:19.056089Z","steps":["trace[1655991721] 'process raft request' (duration: 1.516082951s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.056174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502103289s","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-06-24T17:09:19.056196Z","caller":"traceutil/trace.go:171","msg":"trace[277876555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1122; }","duration":"1.502176811s","start":"2026-06-24T17:09:17.554012Z","end":"2026-06-24T17:09:19.056189Z","steps":["trace[277876555] 'agreement among raft nodes before linearized reading' (duration: 1.502071587s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.056214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:17.553999Z","time spent":"1.502210083s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T17:09:19.056239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50214937s","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-06-24T17:09:19.05627Z","caller":"traceutil/trace.go:171","msg":"trace[1192397429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1122; }","duration":"1.502231464s","start":"2026-06-24T17:09:17.554026Z","end":"2026-06-24T17:09:19.056258Z","steps":["trace[1192397429] 'agreement among raft nodes before linearized reading' (duration: 1.502087979s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.056277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:17.539774Z","time spent":"1.516416954s","remote":"127.0.0.1:45972","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-06-24T17:09:19.056297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:17.554005Z","time spent":"1.502287505s","remote":"127.0.0.1:45972","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-06-24T17:09:19.056316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443303903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:09:19.056334Z","caller":"traceutil/trace.go:171","msg":"trace[254202121] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1122; }","duration":"1.443361835s","start":"2026-06-24T17:09:17.612966Z","end":"2026-06-24T17:09:19.056327Z","steps":["trace[254202121] 'agreement among raft nodes before linearized reading' (duration: 1.443330364s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.056348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:17.612944Z","time spent":"1.443399697s","remote":"127.0.0.1:45984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T17:09:19.056408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501130263s","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-06-24T17:09:19.056422Z","caller":"traceutil/trace.go:171","msg":"trace[177885886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1122; }","duration":"1.501152244s","start":"2026-06-24T17:09:17.555265Z","end":"2026-06-24T17:09:19.056417Z","steps":["trace[177885886] 'agreement among raft nodes before linearized reading' (duration: 1.501110253s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.056441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:17.555259Z","time spent":"1.501179375s","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T17:09:19.056509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502099878s","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-06-24T17:09:19.056537Z","caller":"traceutil/trace.go:171","msg":"trace[1203615122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1122; }","duration":"1.50214578s","start":"2026-06-24T17:09:17.554383Z","end":"2026-06-24T17:09:19.056529Z","steps":["trace[1203615122] 'agreement among raft nodes before linearized reading' (duration: 1.502008175s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.056556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:17.554377Z","time spent":"1.502174201s","remote":"127.0.0.1:45972","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-06-24T17:09:19.622256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.365322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T17:09:19.622329Z","caller":"traceutil/trace.go:171","msg":"trace[1709655553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1122; }","duration":"564.463425ms","start":"2026-06-24T17:09:19.057847Z","end":"2026-06-24T17:09:19.622311Z","steps":["trace[1709655553] 'range keys from in-memory index tree' (duration: 564.232117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.622369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:19.057834Z","time spent":"564.528927ms","remote":"127.0.0.1:45716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T17:09:19.622952Z","caller":"traceutil/trace.go:171","msg":"trace[762614274] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"563.290503ms","start":"2026-06-24T17:09:19.059634Z","end":"2026-06-24T17:09:19.622925Z","steps":["trace[762614274] 'process raft request' (duration: 529.192178ms)","trace[762614274] 'compare' (duration: 33.909128ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T17:09:19.623337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:19.059616Z","time spent":"563.409557ms","remote":"127.0.0.1:45972","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-06-24T17:09:19.624918Z","caller":"traceutil/trace.go:171","msg":"trace[903911555] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"563.912356ms","start":"2026-06-24T17:09:19.060995Z","end":"2026-06-24T17:09:19.624907Z","steps":["trace[903911555] 'process raft request' (duration: 563.873194ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:09:19.624961Z","caller":"traceutil/trace.go:171","msg":"trace[225425809] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"564.04802ms","start":"2026-06-24T17:09:19.060881Z","end":"2026-06-24T17:09:19.624929Z","steps":["trace[225425809] 'process raft request' (duration: 563.912786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:19.060971Z","time spent":"563.980838ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T17:09:19.625096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:19.060866Z","time spent":"564.161225ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:09:19.625173Z","caller":"traceutil/trace.go:171","msg":"trace[498200623] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"563.861154ms","start":"2026-06-24T17:09:19.061304Z","end":"2026-06-24T17:09:19.625165Z","steps":["trace[498200623] 'process raft request' (duration: 563.587504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.625257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:19.061297Z","time spent":"563.916186ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:09:19.625407Z","caller":"traceutil/trace.go:171","msg":"trace[367855580] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"517.2401ms","start":"2026-06-24T17:09:19.10816Z","end":"2026-06-24T17:09:19.6254Z","steps":["trace[367855580] 'process raft request' (duration: 516.930789ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:09:19.625489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T17:09:19.108146Z","time spent":"517.319103ms","remote":"127.0.0.1:45972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T17:10:56.216905Z","caller":"traceutil/trace.go:171","msg":"trace[310690806] linearizableReadLoop","detail":"{readStateIndex:1551; appliedIndex:1550; }","duration":"113.753207ms","start":"2026-06-24T17:10:56.103128Z","end":"2026-06-24T17:10:56.216881Z","steps":["trace[310690806] 'read index received' (duration: 113.597955ms)","trace[310690806] 'applied index is now lower than readState.Index' (duration: 154.262µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T17:10:56.216999Z","caller":"traceutil/trace.go:171","msg":"trace[1707567820] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"123.226356ms","start":"2026-06-24T17:10:56.093748Z","end":"2026-06-24T17:10:56.216974Z","steps":["trace[1707567820] 'process raft request' (duration: 122.984522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:10:56.217111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.963811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-59c82\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-06-24T17:10:56.217154Z","caller":"traceutil/trace.go:171","msg":"trace[2132297303] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-59c82; range_end:; response_count:1; response_revision:1484; }","duration":"114.061023ms","start":"2026-06-24T17:10:56.103085Z","end":"2026-06-24T17:10:56.217146Z","steps":["trace[2132297303] 'agreement among raft nodes before linearized reading' (duration: 113.91672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T17:10:56.217218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.219436ms","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-06-24T17:10:56.217324Z","caller":"traceutil/trace.go:171","msg":"trace[1640429253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1484; }","duration":"101.395119ms","start":"2026-06-24T17:10:56.115912Z","end":"2026-06-24T17:10:56.217308Z","steps":["trace[1640429253] 'agreement among raft nodes before linearized reading' (duration: 101.154645ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:10:56.325447Z","caller":"traceutil/trace.go:171","msg":"trace[846474972] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"103.361493ms","start":"2026-06-24T17:10:56.22206Z","end":"2026-06-24T17:10:56.325421Z","steps":["trace[846474972] 'process raft request' (duration: 103.097008ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:10:56.325794Z","caller":"traceutil/trace.go:171","msg":"trace[536323101] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"103.661228ms","start":"2026-06-24T17:10:56.222114Z","end":"2026-06-24T17:10:56.325775Z","steps":["trace[536323101] 'process raft request' (duration: 103.25792ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T17:10:56.325865Z","caller":"traceutil/trace.go:171","msg":"trace[115932264] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"102.878875ms","start":"2026-06-24T17:10:56.222945Z","end":"2026-06-24T17:10:56.325824Z","steps":["trace[115932264] 'process raft request' (duration: 102.660761ms)"],"step_count":1}