{"level":"warn","ts":"2026-06-12T10:20:55.4177Z","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-12T10:20:55.417851Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.159: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.159:2380","--initial-cluster=instance=https://199.19.213.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.159: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-12T10:20:55.418036Z","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-12T10:20:55.418066Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.159:2380"]} {"level":"info","ts":"2026-06-12T10:20:55.418187Z","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-12T10:20:55.419614Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"]} {"level":"info","ts":"2026-06-12T10:20:55.419774Z","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.159:2380"],"listen-peer-urls":["https://199.19.213.159:2380"],"advertise-client-urls":["https://199.19.213.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.159: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-12T10:20:55.422617Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.394307ms"} {"level":"info","ts":"2026-06-12T10:20:55.431843Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"63cd833aa17a09c7","cluster-id":"27437233b92849be"} {"level":"info","ts":"2026-06-12T10:20:55.431961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=()"} {"level":"info","ts":"2026-06-12T10:20:55.432031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became follower at term 0"} {"level":"info","ts":"2026-06-12T10:20:55.432053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 63cd833aa17a09c7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-12T10:20:55.432125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became follower at term 1"} {"level":"info","ts":"2026-06-12T10:20:55.432299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=(7191548467821021639)"} {"level":"warn","ts":"2026-06-12T10:20:55.436877Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-12T10:20:55.437913Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-12T10:20:55.438867Z","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-12T10:20:55.441445Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"63cd833aa17a09c7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-12T10:20:55.441698Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"63cd833aa17a09c7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-12T10:20:55.441795Z","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-12T10:20:55.442071Z","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-12T10:20:55.442083Z","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-12T10:20:55.442389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=(7191548467821021639)"} {"level":"info","ts":"2026-06-12T10:20:55.442481Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"27437233b92849be","local-member-id":"63cd833aa17a09c7","added-peer-id":"63cd833aa17a09c7","added-peer-peer-urls":["https://199.19.213.159:2380"]} {"level":"info","ts":"2026-06-12T10:20:55.446175Z","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-12T10:20:55.446381Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.159:2380"} {"level":"info","ts":"2026-06-12T10:20:55.446499Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.159:2380"} {"level":"info","ts":"2026-06-12T10:20:55.447134Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-12T10:20:55.446518Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"63cd833aa17a09c7","initial-advertise-peer-urls":["https://199.19.213.159:2380"],"listen-peer-urls":["https://199.19.213.159:2380"],"advertise-client-urls":["https://199.19.213.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-12T10:20:55.832846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 is starting a new election at term 1"} {"level":"info","ts":"2026-06-12T10:20:55.832911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-12T10:20:55.832957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 received MsgPreVoteResp from 63cd833aa17a09c7 at term 1"} {"level":"info","ts":"2026-06-12T10:20:55.832981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became candidate at term 2"} {"level":"info","ts":"2026-06-12T10:20:55.832997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 received MsgVoteResp from 63cd833aa17a09c7 at term 2"} {"level":"info","ts":"2026-06-12T10:20:55.833018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became leader at term 2"} {"level":"info","ts":"2026-06-12T10:20:55.833048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 63cd833aa17a09c7 elected leader 63cd833aa17a09c7 at term 2"} {"level":"info","ts":"2026-06-12T10:20:55.833831Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"63cd833aa17a09c7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.159:2379]}","request-path":"/0/members/63cd833aa17a09c7/attributes","cluster-id":"27437233b92849be","publish-timeout":"7s"} {"level":"info","ts":"2026-06-12T10:20:55.833874Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-12T10:20:55.83431Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T10:20:55.834436Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-12T10:20:55.835468Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"27437233b92849be","local-member-id":"63cd833aa17a09c7","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T10:20:55.83566Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T10:20:55.835708Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T10:20:55.842687Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-12T10:20:55.845193Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-12T10:20:55.845241Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-12T10:20:55.847548Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.159:2379"} {"level":"info","ts":"2026-06-12T10:21:00.466403Z","caller":"traceutil/trace.go:171","msg":"trace[300738190] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"174.824785ms","start":"2026-06-12T10:21:00.291544Z","end":"2026-06-12T10:21:00.466368Z","steps":["trace[300738190] 'process raft request' (duration: 174.462747ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:21:00.793081Z","caller":"traceutil/trace.go:171","msg":"trace[769369992] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"297.432451ms","start":"2026-06-12T10:21:00.49562Z","end":"2026-06-12T10:21:00.793052Z","steps":["trace[769369992] 'process raft request' (duration: 292.286809ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:21:41.833058Z","caller":"traceutil/trace.go:171","msg":"trace[189544032] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"411.368304ms","start":"2026-06-12T10:21:41.421664Z","end":"2026-06-12T10:21:41.833032Z","steps":["trace[189544032] 'read index received' (duration: 407.02536ms)","trace[189544032] 'applied index is now lower than readState.Index' (duration: 4.341774ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:21:41.8331Z","caller":"traceutil/trace.go:171","msg":"trace[1773445546] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"484.49079ms","start":"2026-06-12T10:21:41.348555Z","end":"2026-06-12T10:21:41.833046Z","steps":["trace[1773445546] 'process raft request' (duration: 480.094114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:41.83328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.606149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lgwzs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-12T10:21:41.833409Z","caller":"traceutil/trace.go:171","msg":"trace[1232566635] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lgwzs; range_end:; response_count:1; response_revision:597; }","duration":"411.784073ms","start":"2026-06-12T10:21:41.421603Z","end":"2026-06-12T10:21:41.833387Z","steps":["trace[1232566635] 'agreement among raft nodes before linearized reading' (duration: 411.547598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:41.833392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.582708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-12T10:21:41.833459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:41.421577Z","time spent":"411.872845ms","remote":"127.0.0.1:52704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-lgwzs\" "} {"level":"info","ts":"2026-06-12T10:21:41.833496Z","caller":"traceutil/trace.go:171","msg":"trace[787917889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"367.719641ms","start":"2026-06-12T10:21:41.465739Z","end":"2026-06-12T10:21:41.833459Z","steps":["trace[787917889] 'agreement among raft nodes before linearized reading' (duration: 367.463256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:41.833554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:41.465716Z","time spent":"367.823384ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-12T10:21:41.833733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:41.348527Z","time spent":"484.668073ms","remote":"127.0.0.1:52886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T10:21:41.833825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.090628ms","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-12T10:21:41.833876Z","caller":"traceutil/trace.go:171","msg":"trace[1857542875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"185.17739ms","start":"2026-06-12T10:21:41.648683Z","end":"2026-06-12T10:21:41.83386Z","steps":["trace[1857542875] 'agreement among raft nodes before linearized reading' (duration: 184.515935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:41.833883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.699845ms","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-12T10:21:41.834104Z","caller":"traceutil/trace.go:171","msg":"trace[40949785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"375.006221ms","start":"2026-06-12T10:21:41.459081Z","end":"2026-06-12T10:21:41.834088Z","steps":["trace[40949785] 'agreement among raft nodes before linearized reading' (duration: 374.084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:41.835129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:41.459052Z","time spent":"376.066104ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-12T10:21:43.816409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853277,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:21:44.317062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853277,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:21:44.818428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853277,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:21:45.316364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000311835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T10:21:45.31644Z","caller":"traceutil/trace.go:171","msg":"trace[1014744281] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000438718s","start":"2026-06-12T10:21:43.315984Z","end":"2026-06-12T10:21:45.316423Z","steps":["trace[1014744281] 'agreement among raft nodes before linearized reading' (duration: 2.000344936s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:45.31651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:43.315942Z","time spent":"2.000558751s","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 10:21:45 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:21:45.31879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853277,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:21:45.819403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853277,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:21:46.320512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853277,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:21:46.749587Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.537045619s","expected-duration":"1s"} {"level":"info","ts":"2026-06-12T10:21:46.750022Z","caller":"traceutil/trace.go:171","msg":"trace[853753878] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"3.53753998s","start":"2026-06-12T10:21:43.212456Z","end":"2026-06-12T10:21:46.749996Z","steps":["trace[853753878] 'process raft request' (duration: 3.537377596s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:46.750391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:43.212445Z","time spent":"3.537656272s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:21:46.763733Z","caller":"traceutil/trace.go:171","msg":"trace[1782347151] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"3.327265733s","start":"2026-06-12T10:21:43.436443Z","end":"2026-06-12T10:21:46.763709Z","steps":["trace[1782347151] 'process raft request' (duration: 3.327212202s)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:21:46.763744Z","caller":"traceutil/trace.go:171","msg":"trace[1431142897] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"3.523494993s","start":"2026-06-12T10:21:43.240221Z","end":"2026-06-12T10:21:46.763716Z","steps":["trace[1431142897] 'process raft request' (duration: 3.522999972s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:46.763845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:43.436418Z","time spent":"3.327378105s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":776,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T10:21:46.763885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:43.240172Z","time spent":"3.523638206s","remote":"127.0.0.1:52622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036253,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:21:46.780415Z","caller":"traceutil/trace.go:171","msg":"trace[1775681092] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:633; }","duration":"3.464265921s","start":"2026-06-12T10:21:43.316123Z","end":"2026-06-12T10:21:46.780389Z","steps":["trace[1775681092] 'read index received' (duration: 3.433938279s)","trace[1775681092] 'applied index is now lower than readState.Index' (duration: 30.326292ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:21:46.780644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.345386409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lgwzs\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-06-12T10:21:46.780668Z","caller":"traceutil/trace.go:171","msg":"trace[726027953] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lgwzs; range_end:; response_count:1; response_revision:613; }","duration":"3.34548418s","start":"2026-06-12T10:21:43.435175Z","end":"2026-06-12T10:21:46.780659Z","steps":["trace[726027953] 'agreement among raft nodes before linearized reading' (duration: 3.345346378s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:46.780693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:43.435147Z","time spent":"3.345540131s","remote":"127.0.0.1:52704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17269,"request content":"key:\"/registry/pods/kube-system/cilium-lgwzs\" "} {"level":"warn","ts":"2026-06-12T10:21:46.780865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464112169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:21:46.780883Z","caller":"traceutil/trace.go:171","msg":"trace[381229286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"1.4641626s","start":"2026-06-12T10:21:45.316715Z","end":"2026-06-12T10:21:46.780877Z","steps":["trace[381229286] 'agreement among raft nodes before linearized reading' (duration: 1.46413333s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:46.7809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:45.316696Z","time spent":"1.464200141s","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T10:21:46.781115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.932309267s","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-12T10:21:46.781136Z","caller":"traceutil/trace.go:171","msg":"trace[1197480036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"2.932350149s","start":"2026-06-12T10:21:43.84878Z","end":"2026-06-12T10:21:46.78113Z","steps":["trace[1197480036] 'agreement among raft nodes before linearized reading' (duration: 2.932198995s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:46.781152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:43.84877Z","time spent":"2.932378909s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-12T10:21:46.781336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.933953904s","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-12T10:21:46.781366Z","caller":"traceutil/trace.go:171","msg":"trace[1416091471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"2.934000825s","start":"2026-06-12T10:21:43.847359Z","end":"2026-06-12T10:21:46.78136Z","steps":["trace[1416091471] 'agreement among raft nodes before linearized reading' (duration: 2.933953163s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:46.781382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:43.847352Z","time spent":"2.934026866s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-12T10:21:46.781459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.934831133s","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-12T10:21:46.781475Z","caller":"traceutil/trace.go:171","msg":"trace[1844380673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"2.934898844s","start":"2026-06-12T10:21:43.846572Z","end":"2026-06-12T10:21:46.78147Z","steps":["trace[1844380673] 'agreement among raft nodes before linearized reading' (duration: 2.934870423s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:21:46.781491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:21:43.846541Z","time spent":"2.934946645s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-12T10:22:17.126736Z","caller":"traceutil/trace.go:171","msg":"trace[2041042793] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"145.714919ms","start":"2026-06-12T10:22:16.980982Z","end":"2026-06-12T10:22:17.126697Z","steps":["trace[2041042793] 'read index received' (duration: 97.047718ms)","trace[2041042793] 'applied index is now lower than readState.Index' (duration: 48.666001ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:22:17.12681Z","caller":"traceutil/trace.go:171","msg":"trace[473556865] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"185.757971ms","start":"2026-06-12T10:22:16.941009Z","end":"2026-06-12T10:22:17.126767Z","steps":["trace[473556865] 'process raft request' (duration: 137.113101ms)","trace[473556865] 'compare' (duration: 48.392365ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:22:17.127123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.073686ms","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-12T10:22:17.127171Z","caller":"traceutil/trace.go:171","msg":"trace[556309330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"146.22088ms","start":"2026-06-12T10:22:16.980936Z","end":"2026-06-12T10:22:17.127157Z","steps":["trace[556309330] 'agreement among raft nodes before linearized reading' (duration: 145.965594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:17.127336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.153634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-12T10:22:17.127388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.621923ms","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-12T10:22:17.127473Z","caller":"traceutil/trace.go:171","msg":"trace[687113498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"138.326687ms","start":"2026-06-12T10:22:16.989118Z","end":"2026-06-12T10:22:17.127445Z","steps":["trace[687113498] 'agreement among raft nodes before linearized reading' (duration: 137.954039ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:17.127468Z","caller":"traceutil/trace.go:171","msg":"trace[107970140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"138.738096ms","start":"2026-06-12T10:22:16.988712Z","end":"2026-06-12T10:22:17.12745Z","steps":["trace[107970140] 'agreement among raft nodes before linearized reading' (duration: 138.254886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:17.634937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853883,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:17.682043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.95249ms","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-06-12T10:22:17.682205Z","caller":"traceutil/trace.go:171","msg":"trace[1872914513] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:921; }","duration":"139.131174ms","start":"2026-06-12T10:22:17.54305Z","end":"2026-06-12T10:22:17.682181Z","steps":["trace[1872914513] 'range keys from in-memory index tree' (duration: 138.92944ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:17.682516Z","caller":"traceutil/trace.go:171","msg":"trace[942266323] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"550.848445ms","start":"2026-06-12T10:22:17.131626Z","end":"2026-06-12T10:22:17.682475Z","steps":["trace[942266323] 'process raft request' (duration: 503.41258ms)","trace[942266323] 'compare' (duration: 46.674649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:22:17.682714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:17.131602Z","time spent":"551.006789ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:22:17.695965Z","caller":"traceutil/trace.go:171","msg":"trace[1352224317] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:953; }","duration":"561.457627ms","start":"2026-06-12T10:22:17.134489Z","end":"2026-06-12T10:22:17.695946Z","steps":["trace[1352224317] 'read index received' (duration: 500.671121ms)","trace[1352224317] 'applied index is now lower than readState.Index' (duration: 60.785566ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:22:17.696127Z","caller":"traceutil/trace.go:171","msg":"trace[534612397] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"563.033442ms","start":"2026-06-12T10:22:17.133026Z","end":"2026-06-12T10:22:17.696059Z","steps":["trace[534612397] 'process raft request' (duration: 562.888029ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:17.696078Z","caller":"traceutil/trace.go:171","msg":"trace[1729839138] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"563.025721ms","start":"2026-06-12T10:22:17.133019Z","end":"2026-06-12T10:22:17.696044Z","steps":["trace[1729839138] 'process raft request' (duration: 562.777266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:17.696229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.724588ms","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-12T10:22:17.69629Z","caller":"traceutil/trace.go:171","msg":"trace[1008337073] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:924; }","duration":"409.84364ms","start":"2026-06-12T10:22:17.28643Z","end":"2026-06-12T10:22:17.696273Z","steps":["trace[1008337073] 'agreement among raft nodes before linearized reading' (duration: 409.736428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:17.696247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:17.132983Z","time spent":"563.188475ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T10:22:17.696339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:17.286404Z","time spent":"409.920762ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-12T10:22:17.696359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.780804ms","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":"warn","ts":"2026-06-12T10:22:17.696332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.476848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:22:17.696464Z","caller":"traceutil/trace.go:171","msg":"trace[926261135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"379.665152ms","start":"2026-06-12T10:22:17.316766Z","end":"2026-06-12T10:22:17.696432Z","steps":["trace[926261135] 'agreement among raft nodes before linearized reading' (duration: 379.441487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:17.696528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:17.31674Z","time spent":"379.775924ms","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T10:22:17.696536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:17.132905Z","time spent":"563.300498ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:22:17.696404Z","caller":"traceutil/trace.go:171","msg":"trace[1961032797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"379.892947ms","start":"2026-06-12T10:22:17.316499Z","end":"2026-06-12T10:22:17.696392Z","steps":["trace[1961032797] 'agreement among raft nodes before linearized reading' (duration: 379.711093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:17.696628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:17.316423Z","time spent":"380.196523ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-12T10:22:17.697095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.595733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-12T10:22:17.697141Z","caller":"traceutil/trace.go:171","msg":"trace[1638679794] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:924; }","duration":"562.644544ms","start":"2026-06-12T10:22:17.134485Z","end":"2026-06-12T10:22:17.69713Z","steps":["trace[1638679794] 'agreement among raft nodes before linearized reading' (duration: 561.670422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:17.697174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:17.134475Z","time spent":"562.690875ms","remote":"127.0.0.1:52814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-06-12T10:22:17.992227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.039218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T10:22:17.992354Z","caller":"traceutil/trace.go:171","msg":"trace[899797550] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"291.668702ms","start":"2026-06-12T10:22:17.700662Z","end":"2026-06-12T10:22:17.992331Z","steps":["trace[899797550] 'process raft request' (duration: 100.280877ms)","trace[899797550] 'compare' (duration: 190.66422ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:22:19.816025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:20.316874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:20.81717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:21.315813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999994149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-12T10:22:21.31593Z","caller":"traceutil/trace.go:171","msg":"trace[800338580] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000164832s","start":"2026-06-12T10:22:19.315741Z","end":"2026-06-12T10:22:21.315906Z","steps":["trace[800338580] 'agreement among raft nodes before linearized reading' (duration: 2.000029139s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:21.315983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:19.315714Z","time spent":"2.000259344s","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-12T10:22:21.317392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:21.818003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:22.318472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:22.819233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:23.316929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000344867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-12T10:22:23.317133Z","caller":"traceutil/trace.go:171","msg":"trace[1362229663] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000617693s","start":"2026-06-12T10:22:21.316486Z","end":"2026-06-12T10:22:23.317104Z","steps":["trace[1362229663] 'agreement among raft nodes before linearized reading' (duration: 2.000379918s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:23.317225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:21.316467Z","time spent":"2.000743875s","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 10:22:23 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:23.320233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:23.8208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:23.959831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:18.960926Z","time spent":"4.998885508s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/12 10:22:23 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:24.13105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:19.133329Z","time spent":"4.997707323s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/12 10:22:24 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:24.320981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:24.68666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999280927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-12T10:22:24.686771Z","caller":"traceutil/trace.go:171","msg":"trace[1147452465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999452181s","start":"2026-06-12T10:22:19.687292Z","end":"2026-06-12T10:22:24.686744Z","steps":["trace[1147452465] 'agreement among raft nodes before linearized reading' (duration: 4.999307828s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:24.686834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:19.687269Z","time spent":"4.999554403s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/06/12 10:22:24 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:24.821265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:24.996837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999702476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-12T10:22:24.996958Z","caller":"traceutil/trace.go:171","msg":"trace[2134928632] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.99990287s","start":"2026-06-12T10:22:19.997028Z","end":"2026-06-12T10:22:24.996931Z","steps":["trace[2134928632] 'agreement among raft nodes before linearized reading' (duration: 4.999742416s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:24.997021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:19.997006Z","time spent":"5.000002262s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-12T10:22:25.322299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:25.32675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000271435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T10:22:25.32689Z","caller":"traceutil/trace.go:171","msg":"trace[414198727] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000489249s","start":"2026-06-12T10:22:23.32637Z","end":"2026-06-12T10:22:25.326859Z","steps":["trace[414198727] 'agreement among raft nodes before linearized reading' (duration: 2.000319696s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:25.326969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:23.326348Z","time spent":"2.000604342s","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 10:22:25 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:25.822479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:26.316303Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-06-12T10:22:26.817377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:27.3182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:27.327761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000553132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-12T10:22:27.328092Z","caller":"traceutil/trace.go:171","msg":"trace[881672884] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00091636s","start":"2026-06-12T10:22:25.32714Z","end":"2026-06-12T10:22:27.328056Z","steps":["trace[881672884] 'agreement among raft nodes before linearized reading' (duration: 2.000547451s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:27.328316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:25.327065Z","time spent":"2.001228176s","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 10:22:27 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:27.819284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:28.320063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:28.82066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:28.956312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.994541475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T10:22:28.956461Z","caller":"traceutil/trace.go:171","msg":"trace[48734327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.994765849s","start":"2026-06-12T10:22:25.961673Z","end":"2026-06-12T10:22:28.956439Z","steps":["trace[48734327] 'agreement among raft nodes before linearized reading' (duration: 2.994586205s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:28.956538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:25.961645Z","time spent":"2.994883192s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/06/12 10:22:28 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:29.13139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998294665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T10:22:29.131518Z","caller":"traceutil/trace.go:171","msg":"trace[973818161] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.99850934s","start":"2026-06-12T10:22:24.132978Z","end":"2026-06-12T10:22:29.131487Z","steps":["trace[973818161] 'agreement among raft nodes before linearized reading' (duration: 4.998345537s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:29.131594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:24.132949Z","time spent":"4.998630592s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/06/12 10:22:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:29.320963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:29.33581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999953138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-12T10:22:29.335914Z","caller":"traceutil/trace.go:171","msg":"trace[968028107] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000139332s","start":"2026-06-12T10:22:27.335748Z","end":"2026-06-12T10:22:29.335887Z","steps":["trace[968028107] 'agreement among raft nodes before linearized reading' (duration: 1.999999269s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:29.335978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:27.33571Z","time spent":"2.000257034s","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/12 10:22:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:29.686891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997897439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T10:22:29.687177Z","caller":"traceutil/trace.go:171","msg":"trace[1799886277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.998218125s","start":"2026-06-12T10:22:26.688884Z","end":"2026-06-12T10:22:29.687102Z","steps":["trace[1799886277] 'agreement among raft nodes before linearized reading' (duration: 2.997942609s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:29.687294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:26.688859Z","time spent":"2.99841618s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/06/12 10:22:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:29.701272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000165569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T10:22:29.701388Z","caller":"traceutil/trace.go:171","msg":"trace[57323883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.000337473s","start":"2026-06-12T10:22:19.701026Z","end":"2026-06-12T10:22:29.701363Z","steps":["trace[57323883] 'agreement among raft nodes before linearized reading' (duration: 10.000176869s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:29.701363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000053547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"warn","ts":"2026-06-12T10:22:29.701452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:19.701009Z","time spent":"10.000430145s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-12T10:22:29.701465Z","caller":"traceutil/trace.go:171","msg":"trace[1501080000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.00021954s","start":"2026-06-12T10:22:19.701219Z","end":"2026-06-12T10:22:29.701439Z","steps":["trace[1501080000] 'agreement among raft nodes before linearized reading' (duration: 10.000093588s)"],"step_count":1} 2026/06/12 10:22:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:29.701529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:19.701193Z","time spent":"10.000324433s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/06/12 10:22:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:29.822167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:29.996712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997310756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-12T10:22:29.996826Z","caller":"traceutil/trace.go:171","msg":"trace[5170458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997504069s","start":"2026-06-12T10:22:26.999296Z","end":"2026-06-12T10:22:29.9968Z","steps":["trace[5170458] 'agreement among raft nodes before linearized reading' (duration: 2.997345976s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:29.996891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:26.99927Z","time spent":"2.997610002s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/06/12 10:22:29 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-12T10:22:30.3229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":704706394201853906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-12T10:22:30.489511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:23.488925Z","time spent":"7.000574494s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-12T10:22:30.653515Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"11.692411565s","expected-duration":"1s"} {"level":"info","ts":"2026-06-12T10:22:30.730933Z","caller":"traceutil/trace.go:171","msg":"trace[752165422] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.59256348s","start":"2026-06-12T10:22:29.138318Z","end":"2026-06-12T10:22:30.730881Z","steps":["trace[752165422] 'process raft request' (duration: 1.592288414s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.731072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:29.138285Z","time spent":"1.592721264s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":904,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-12T10:22:30.730945Z","caller":"traceutil/trace.go:171","msg":"trace[663463207] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:960; }","duration":"4.414463999s","start":"2026-06-12T10:22:26.316421Z","end":"2026-06-12T10:22:30.730885Z","steps":["trace[663463207] 'read index received' (duration: 4.337355298s)","trace[663463207] 'applied index is now lower than readState.Index' (duration: 77.107821ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:22:30.731177Z","caller":"traceutil/trace.go:171","msg":"trace[1100900743] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"730.70659ms","start":"2026-06-12T10:22:30.000402Z","end":"2026-06-12T10:22:30.731108Z","steps":["trace[1100900743] 'process raft request' (duration: 730.394284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.731261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.234583819s","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":"warn","ts":"2026-06-12T10:22:30.731351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.395149789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:22:30.73137Z","caller":"traceutil/trace.go:171","msg":"trace[551438803] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:936; }","duration":"5.234789494s","start":"2026-06-12T10:22:25.496569Z","end":"2026-06-12T10:22:30.731358Z","steps":["trace[551438803] 'agreement among raft nodes before linearized reading' (duration: 5.234545018s)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:30.731399Z","caller":"traceutil/trace.go:171","msg":"trace[1223011096] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:936; }","duration":"2.395254841s","start":"2026-06-12T10:22:28.336133Z","end":"2026-06-12T10:22:30.731388Z","steps":["trace[1223011096] 'agreement among raft nodes before linearized reading' (duration: 2.395172909s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.731395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:30.000366Z","time spent":"730.910764ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T10:22:30.731416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:25.49654Z","time spent":"5.234864996s","remote":"127.0.0.1:53064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":95,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-12T10:22:30.731436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:28.3361Z","time spent":"2.395327403s","remote":"127.0.0.1:52754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-12T10:22:30.731451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.409306091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b84e440b985e5a\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-06-12T10:22:30.731554Z","caller":"traceutil/trace.go:171","msg":"trace[1697038822] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18b84e440b985e5a; range_end:; response_count:1; response_revision:936; }","duration":"7.409579036s","start":"2026-06-12T10:22:23.321958Z","end":"2026-06-12T10:22:30.731537Z","steps":["trace[1697038822] 'agreement among raft nodes before linearized reading' (duration: 7.409173438s)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:30.731273Z","caller":"traceutil/trace.go:171","msg":"trace[1182909332] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.902517082s","start":"2026-06-12T10:22:28.828708Z","end":"2026-06-12T10:22:30.731225Z","steps":["trace[1182909332] 'process raft request' (duration: 1.901657673s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.731617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:23.321867Z","time spent":"7.40973814s","remote":"127.0.0.1:52606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b84e440b985e5a\" "} {"level":"warn","ts":"2026-06-12T10:22:30.731702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:28.828679Z","time spent":"1.902956262s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T10:22:30.731555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395056402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:22:30.731848Z","caller":"traceutil/trace.go:171","msg":"trace[1516708859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"1.395426742s","start":"2026-06-12T10:22:29.336411Z","end":"2026-06-12T10:22:30.731838Z","steps":["trace[1516708859] 'agreement among raft nodes before linearized reading' (duration: 1.395042703s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.731905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:29.336377Z","time spent":"1.395514953s","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-12T10:22:30.731944Z","caller":"traceutil/trace.go:171","msg":"trace[1046812422] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.027893583s","start":"2026-06-12T10:22:29.704043Z","end":"2026-06-12T10:22:30.731937Z","steps":["trace[1046812422] 'process raft request' (duration: 1.026695746s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.731979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.240840513s","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-12T10:22:30.732008Z","caller":"traceutil/trace.go:171","msg":"trace[430635085] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:936; }","duration":"2.240915344s","start":"2026-06-12T10:22:28.491085Z","end":"2026-06-12T10:22:30.732Z","steps":["trace[430635085] 'agreement among raft nodes before linearized reading' (duration: 2.240880414s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.732014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.934926729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.159\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-12T10:22:30.732034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:28.491056Z","time spent":"2.240971876s","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-12T10:22:30.732054Z","caller":"traceutil/trace.go:171","msg":"trace[610581943] range","detail":"{range_begin:/registry/masterleases/199.19.213.159; range_end:; response_count:1; response_revision:936; }","duration":"1.935019701s","start":"2026-06-12T10:22:28.79702Z","end":"2026-06-12T10:22:30.732039Z","steps":["trace[610581943] 'agreement among raft nodes before linearized reading' (duration: 1.93455015s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.732077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:28.796997Z","time spent":"1.935074803s","remote":"127.0.0.1:52554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.159\" "} {"level":"info","ts":"2026-06-12T10:22:30.731821Z","caller":"traceutil/trace.go:171","msg":"trace[791706031] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"1.026910551s","start":"2026-06-12T10:22:29.704086Z","end":"2026-06-12T10:22:30.730996Z","steps":["trace[791706031] 'process raft request' (duration: 1.026683296s)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:30.731915Z","caller":"traceutil/trace.go:171","msg":"trace[1211913036] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.042607264s","start":"2026-06-12T10:22:29.689297Z","end":"2026-06-12T10:22:30.731904Z","steps":["trace[1211913036] 'process raft request' (duration: 1.041377167s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.732177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:29.68927Z","time spent":"1.042884401s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T10:22:30.732188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:29.704055Z","time spent":"1.028090546s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T10:22:30.732103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:29.703947Z","time spent":"1.028048546s","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T10:22:30.731948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.35391478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-12T10:22:30.732294Z","caller":"traceutil/trace.go:171","msg":"trace[1747549275] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:936; }","duration":"2.354293828s","start":"2026-06-12T10:22:28.377986Z","end":"2026-06-12T10:22:30.73228Z","steps":["trace[1747549275] 'agreement among raft nodes before linearized reading' (duration: 2.353934371s)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:30.732337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:22:28.37797Z","time spent":"2.354350789s","remote":"127.0.0.1:52992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-06-12T10:22:30.731073Z","caller":"traceutil/trace.go:171","msg":"trace[874078201] transaction","detail":"{read_only:false; number_of_response:1; response_revision:936; }","duration":"236.06616ms","start":"2026-06-12T10:22:30.494964Z","end":"2026-06-12T10:22:30.73103Z","steps":["trace[874078201] 'process raft request' (duration: 235.882206ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:42.967077Z","caller":"traceutil/trace.go:171","msg":"trace[2097557193] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"200.988959ms","start":"2026-06-12T10:22:42.766055Z","end":"2026-06-12T10:22:42.967044Z","steps":["trace[2097557193] 'process raft request' (duration: 200.807485ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:08.322642Z","caller":"traceutil/trace.go:171","msg":"trace[1504648060] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"235.534961ms","start":"2026-06-12T10:23:08.087074Z","end":"2026-06-12T10:23:08.322609Z","steps":["trace[1504648060] 'process raft request' (duration: 235.348207ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:08.335701Z","caller":"traceutil/trace.go:171","msg":"trace[2005570537] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"144.540817ms","start":"2026-06-12T10:23:08.191132Z","end":"2026-06-12T10:23:08.335673Z","steps":["trace[2005570537] 'read index received' (duration: 132.030465ms)","trace[2005570537] 'applied index is now lower than readState.Index' (duration: 12.509092ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:23:08.335792Z","caller":"traceutil/trace.go:171","msg":"trace[1010182050] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"247.644395ms","start":"2026-06-12T10:23:08.088112Z","end":"2026-06-12T10:23:08.335756Z","steps":["trace[1010182050] 'process raft request' (duration: 247.42261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:08.335923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.770072ms","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-12T10:23:08.335952Z","caller":"traceutil/trace.go:171","msg":"trace[1816798218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1085; }","duration":"144.868454ms","start":"2026-06-12T10:23:08.191077Z","end":"2026-06-12T10:23:08.335945Z","steps":["trace[1816798218] 'agreement among raft nodes before linearized reading' (duration: 144.712321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:09.512911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.4888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T10:23:09.513026Z","caller":"traceutil/trace.go:171","msg":"trace[1285226106] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"195.144605ms","start":"2026-06-12T10:23:09.317863Z","end":"2026-06-12T10:23:09.513007Z","steps":["trace[1285226106] 'read index received' (duration: 128.373µs)","trace[1285226106] 'applied index is now lower than readState.Index' (duration: 195.014922ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:23:09.513124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.278918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:23:09.513149Z","caller":"traceutil/trace.go:171","msg":"trace[913454099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"195.351009ms","start":"2026-06-12T10:23:09.317789Z","end":"2026-06-12T10:23:09.51314Z","steps":["trace[913454099] 'agreement among raft nodes before linearized reading' (duration: 195.266297ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:09.51349Z","caller":"traceutil/trace.go:171","msg":"trace[1090336579] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"369.908985ms","start":"2026-06-12T10:23:09.143549Z","end":"2026-06-12T10:23:09.513458Z","steps":["trace[1090336579] 'process raft request' (duration: 165.759181ms)","trace[1090336579] 'compare' (duration: 203.274696ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:23:09.513669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:23:09.143527Z","time spent":"370.034108ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:23:26.748769Z","caller":"traceutil/trace.go:171","msg":"trace[1385224270] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"204.977772ms","start":"2026-06-12T10:23:26.54377Z","end":"2026-06-12T10:23:26.748748Z","steps":["trace[1385224270] 'read index received' (duration: 204.806588ms)","trace[1385224270] 'applied index is now lower than readState.Index' (duration: 170.564µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:23:26.749105Z","caller":"traceutil/trace.go:171","msg":"trace[44919623] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"209.569098ms","start":"2026-06-12T10:23:26.539526Z","end":"2026-06-12T10:23:26.749095Z","steps":["trace[44919623] 'process raft request' (duration: 209.106238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:26.749294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.516523ms","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-12T10:23:26.749312Z","caller":"traceutil/trace.go:171","msg":"trace[719620385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1196; }","duration":"205.587114ms","start":"2026-06-12T10:23:26.543718Z","end":"2026-06-12T10:23:26.749306Z","steps":["trace[719620385] 'agreement among raft nodes before linearized reading' (duration: 205.498162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:26.749414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.884089ms","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-12T10:23:26.749427Z","caller":"traceutil/trace.go:171","msg":"trace[382283446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1196; }","duration":"193.971901ms","start":"2026-06-12T10:23:26.555451Z","end":"2026-06-12T10:23:26.749423Z","steps":["trace[382283446] 'agreement among raft nodes before linearized reading' (duration: 193.9292ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:31.003074Z","caller":"traceutil/trace.go:171","msg":"trace[899182702] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"220.892194ms","start":"2026-06-12T10:23:30.782156Z","end":"2026-06-12T10:23:31.003048Z","steps":["trace[899182702] 'read index received' (duration: 220.746861ms)","trace[899182702] 'applied index is now lower than readState.Index' (duration: 143.833µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:23:31.003572Z","caller":"traceutil/trace.go:171","msg":"trace[326729017] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"221.489387ms","start":"2026-06-12T10:23:30.782067Z","end":"2026-06-12T10:23:31.003557Z","steps":["trace[326729017] 'process raft request' (duration: 220.770862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:31.003858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.683461ms","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-12T10:23:31.003891Z","caller":"traceutil/trace.go:171","msg":"trace[1895151447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1207; }","duration":"221.764823ms","start":"2026-06-12T10:23:30.782117Z","end":"2026-06-12T10:23:31.003882Z","steps":["trace[1895151447] 'agreement among raft nodes before linearized reading' (duration: 221.61927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:31.004022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.806144ms","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-12T10:23:31.004094Z","caller":"traceutil/trace.go:171","msg":"trace[12281754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1207; }","duration":"221.890915ms","start":"2026-06-12T10:23:30.782192Z","end":"2026-06-12T10:23:31.004083Z","steps":["trace[12281754] 'agreement among raft nodes before linearized reading' (duration: 221.721652ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:41.381301Z","caller":"traceutil/trace.go:171","msg":"trace[675720820] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"240.811642ms","start":"2026-06-12T10:23:41.140466Z","end":"2026-06-12T10:23:41.381278Z","steps":["trace[675720820] 'process raft request' (duration: 240.205549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:41.963026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.721504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:23:41.963267Z","caller":"traceutil/trace.go:171","msg":"trace[1392907041] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1245; }","duration":"160.069852ms","start":"2026-06-12T10:23:41.803169Z","end":"2026-06-12T10:23:41.963239Z","steps":["trace[1392907041] 'count revisions from in-memory index tree' (duration: 159.618332ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:42.511514Z","caller":"traceutil/trace.go:171","msg":"trace[2103356802] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"265.54513ms","start":"2026-06-12T10:23:42.245947Z","end":"2026-06-12T10:23:42.511492Z","steps":["trace[2103356802] 'read index received' (duration: 265.353466ms)","trace[2103356802] 'applied index is now lower than readState.Index' (duration: 191.054µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:23:42.51168Z","caller":"traceutil/trace.go:171","msg":"trace[403929232] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"333.776208ms","start":"2026-06-12T10:23:42.17786Z","end":"2026-06-12T10:23:42.511637Z","steps":["trace[403929232] 'process raft request' (duration: 333.511683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:42.511838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.691501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-12T10:23:42.511902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.167782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"warn","ts":"2026-06-12T10:23:42.511918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.727884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:23:42.511939Z","caller":"traceutil/trace.go:171","msg":"trace[1198201461] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1247; }","duration":"158.244724ms","start":"2026-06-12T10:23:42.353682Z","end":"2026-06-12T10:23:42.511927Z","steps":["trace[1198201461] 'agreement among raft nodes before linearized reading' (duration: 158.079391ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:42.511946Z","caller":"traceutil/trace.go:171","msg":"trace[1864001647] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1247; }","duration":"266.023949ms","start":"2026-06-12T10:23:42.245915Z","end":"2026-06-12T10:23:42.511939Z","steps":["trace[1864001647] 'agreement among raft nodes before linearized reading' (duration: 265.686403ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:42.511938Z","caller":"traceutil/trace.go:171","msg":"trace[1541524721] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1247; }","duration":"263.816983ms","start":"2026-06-12T10:23:42.2481Z","end":"2026-06-12T10:23:42.511917Z","steps":["trace[1541524721] 'agreement among raft nodes before linearized reading' (duration: 263.64975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:42.512046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.564441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:23:42.512225Z","caller":"traceutil/trace.go:171","msg":"trace[802363964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"191.779946ms","start":"2026-06-12T10:23:42.320362Z","end":"2026-06-12T10:23:42.512142Z","steps":["trace[802363964] 'agreement among raft nodes before linearized reading' (duration: 191.52055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:23:42.512492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:23:42.177837Z","time spent":"334.578025ms","remote":"127.0.0.1:52800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:23:43.197364Z","caller":"traceutil/trace.go:171","msg":"trace[322062423] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"118.906919ms","start":"2026-06-12T10:23:43.078425Z","end":"2026-06-12T10:23:43.197332Z","steps":["trace[322062423] 'process raft request' (duration: 118.612813ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:43.308799Z","caller":"traceutil/trace.go:171","msg":"trace[1269296533] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"106.327777ms","start":"2026-06-12T10:23:43.202435Z","end":"2026-06-12T10:23:43.308763Z","steps":["trace[1269296533] 'process raft request' (duration: 106.085342ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:59.669448Z","caller":"traceutil/trace.go:171","msg":"trace[1112229574] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"267.935954ms","start":"2026-06-12T10:23:59.401473Z","end":"2026-06-12T10:23:59.669409Z","steps":["trace[1112229574] 'process raft request' (duration: 170.661773ms)","trace[1112229574] 'compare' (duration: 96.906644ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:23:59.669668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.572395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:23:59.669778Z","caller":"traceutil/trace.go:171","msg":"trace[1113896245] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1374; }","duration":"207.754459ms","start":"2026-06-12T10:23:59.462002Z","end":"2026-06-12T10:23:59.669756Z","steps":["trace[1113896245] 'agreement among raft nodes before linearized reading' (duration: 207.559205ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:23:59.669502Z","caller":"traceutil/trace.go:171","msg":"trace[545580893] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"207.38144ms","start":"2026-06-12T10:23:59.462049Z","end":"2026-06-12T10:23:59.66943Z","steps":["trace[545580893] 'read index received' (duration: 109.941926ms)","trace[545580893] 'applied index is now lower than readState.Index' (duration: 97.437714ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:23:59.670498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.819025ms","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-12T10:23:59.670557Z","caller":"traceutil/trace.go:171","msg":"trace[491444613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1374; }","duration":"132.938698ms","start":"2026-06-12T10:23:59.537596Z","end":"2026-06-12T10:23:59.670535Z","steps":["trace[491444613] 'agreement among raft nodes before linearized reading' (duration: 132.678393ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:24:51.86919Z","caller":"traceutil/trace.go:171","msg":"trace[1535223733] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"249.612ms","start":"2026-06-12T10:24:51.61954Z","end":"2026-06-12T10:24:51.869152Z","steps":["trace[1535223733] 'process raft request' (duration: 249.403326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:27:50.948436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.554925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T10:27:50.948764Z","caller":"traceutil/trace.go:171","msg":"trace[1312677723] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"151.162497ms","start":"2026-06-12T10:27:50.797581Z","end":"2026-06-12T10:27:50.948744Z","steps":["trace[1312677723] 'process raft request' (duration: 35.237125ms)","trace[1312677723] 'compare' (duration: 115.271069ms)"],"step_count":2}