{"level":"warn","ts":"2026-07-17T18:07:12.769637Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T18:07:12.76971Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T18:07:12.769769Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T18:07:12.769781Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-17T18:07:12.769797Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T18:07:12.770529Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-07-17T18:07:12.770888Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T18:07:12.775546Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.468081ms"} {"level":"info","ts":"2026-07-17T18:07:12.779704Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-17T18:07:12.77976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T18:07:12.779775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-17T18:07:12.779786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T18:07:12.779793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-17T18:07:12.779826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-17T18:07:12.792183Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T18:07:12.794143Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T18:07:12.797814Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T18:07:12.800896Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T18:07:12.801178Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T18:07:12.801244Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T18:07:12.801254Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T18:07:12.80154Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T18:07:12.801939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-17T18:07:12.803528Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-17T18:07:12.805111Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T18:07:12.805213Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-17T18:07:12.805328Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-17T18:07:12.80601Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T18:07:12.806039Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T18:07:13.2805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T18:07:13.280585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T18:07:13.280634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-17T18:07:13.280659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-17T18:07:13.280675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-17T18:07:13.280696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-17T18:07:13.280714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-17T18:07:13.350493Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T18:07:13.350982Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T18:07:13.35106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T18:07:13.35114Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T18:07:13.351411Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T18:07:13.351567Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T18:07:13.35364Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T18:07:13.353822Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T18:07:13.353894Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T18:07:13.357254Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T18:07:13.357432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"warn","ts":"2026-07-17T18:07:15.268144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122667947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:07:15.768845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122667947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:07:16.043795Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.284290154s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T18:07:16.482482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.010842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-17T18:07:16.482675Z","caller":"traceutil/trace.go:171","msg":"trace[1800718204] linearizableReadLoop","detail":"{readStateIndex:43; appliedIndex:39; }","duration":"1.715065716s","start":"2026-07-17T18:07:14.767596Z","end":"2026-07-17T18:07:16.482662Z","steps":["trace[1800718204] 'read index received' (duration: 1.276414483s)","trace[1800718204] 'applied index is now lower than readState.Index' (duration: 438.650513ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:07:16.482751Z","caller":"traceutil/trace.go:171","msg":"trace[720690262] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"1.747214798s","start":"2026-07-17T18:07:14.735528Z","end":"2026-07-17T18:07:16.482743Z","steps":["trace[720690262] 'process raft request' (duration: 1.308561315s)","trace[720690262] 'compare' (duration: 437.877648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:07:16.482793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:14.735519Z","time spent":"1.747256349s","remote":"127.0.0.1:53910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:16.483105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.71548688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:16.483131Z","caller":"traceutil/trace.go:171","msg":"trace[482070573] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:39; }","duration":"1.715565553s","start":"2026-07-17T18:07:14.767558Z","end":"2026-07-17T18:07:16.483123Z","steps":["trace[482070573] 'agreement among raft nodes before linearized reading' (duration: 1.71547853s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:16.483149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:14.767543Z","time spent":"1.715602193s","remote":"127.0.0.1:53920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-07-17T18:07:16.483179Z","caller":"traceutil/trace.go:171","msg":"trace[2061528423] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"1.747078143s","start":"2026-07-17T18:07:14.736056Z","end":"2026-07-17T18:07:16.483134Z","steps":["trace[2061528423] 'process raft request' (duration: 1.746546226s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:16.483176Z","caller":"traceutil/trace.go:171","msg":"trace[976768547] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"1.733859713s","start":"2026-07-17T18:07:14.749224Z","end":"2026-07-17T18:07:16.483083Z","steps":["trace[976768547] 'process raft request' (duration: 1.733400809s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:16.483304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.899182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-17T18:07:16.483347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:14.73605Z","time spent":"1.747201268s","remote":"127.0.0.1:53920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:16.483394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503468205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:16.483395Z","caller":"traceutil/trace.go:171","msg":"trace[63153703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:39; }","duration":"780.035687ms","start":"2026-07-17T18:07:15.70334Z","end":"2026-07-17T18:07:16.483376Z","steps":["trace[63153703] 'agreement among raft nodes before linearized reading' (duration: 779.850761ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:16.483421Z","caller":"traceutil/trace.go:171","msg":"trace[288089460] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:39; }","duration":"1.503522397s","start":"2026-07-17T18:07:14.979889Z","end":"2026-07-17T18:07:16.483412Z","steps":["trace[288089460] 'agreement among raft nodes before linearized reading' (duration: 1.503482336s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:16.483417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:14.749212Z","time spent":"1.734058509s","remote":"127.0.0.1:53910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:16.483434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:15.703278Z","time spent":"780.14786ms","remote":"127.0.0.1:53816","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-07-17T18:07:16.483446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:14.979869Z","time spent":"1.503572039s","remote":"127.0.0.1:53940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-07-17T18:07:16.483367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603662067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:16.483596Z","caller":"traceutil/trace.go:171","msg":"trace[1425091530] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:39; }","duration":"1.604066601s","start":"2026-07-17T18:07:14.87951Z","end":"2026-07-17T18:07:16.483577Z","steps":["trace[1425091530] 'agreement among raft nodes before linearized reading' (duration: 1.603523784s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:16.483639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514577058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:16.483672Z","caller":"traceutil/trace.go:171","msg":"trace[911275882] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:39; }","duration":"1.51463554s","start":"2026-07-17T18:07:14.969029Z","end":"2026-07-17T18:07:16.483665Z","steps":["trace[911275882] 'agreement among raft nodes before linearized reading' (duration: 1.514594919s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:16.48369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:14.96901Z","time spent":"1.514675402s","remote":"127.0.0.1:54212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-17T18:07:16.483746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:14.87949Z","time spent":"1.604217145s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-07-17T18:07:16.483545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.964231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:16.483892Z","caller":"traceutil/trace.go:171","msg":"trace[1882987541] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:39; }","duration":"886.375114ms","start":"2026-07-17T18:07:15.59751Z","end":"2026-07-17T18:07:16.483885Z","steps":["trace[1882987541] 'agreement among raft nodes before linearized reading' (duration: 886.017462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:16.483917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:15.597486Z","time spent":"886.424506ms","remote":"127.0.0.1:54184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-07-17T18:07:16.484076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.157477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:16.484168Z","caller":"traceutil/trace.go:171","msg":"trace[1290172000] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:39; }","duration":"886.333932ms","start":"2026-07-17T18:07:15.597816Z","end":"2026-07-17T18:07:16.48415Z","steps":["trace[1290172000] 'agreement among raft nodes before linearized reading' (duration: 885.219077ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:16.484608Z","caller":"traceutil/trace.go:171","msg":"trace[1150428430] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"1.748635363s","start":"2026-07-17T18:07:14.73592Z","end":"2026-07-17T18:07:16.484555Z","steps":["trace[1150428430] 'process raft request' (duration: 1.74664642s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:16.484636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:15.597782Z","time spent":"886.840869ms","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-07-17T18:07:16.484703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:14.735912Z","time spent":"1.748756737s","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:16.987495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122667954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:07:17.487641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122667954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:07:17.988101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122667954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:07:18.397287Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.911270064s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T18:07:18.399107Z","caller":"traceutil/trace.go:171","msg":"trace[1973494520] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"1.913363594s","start":"2026-07-17T18:07:16.485727Z","end":"2026-07-17T18:07:18.39909Z","steps":["trace[1973494520] 'process raft request' (duration: 1.911888635s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.399244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.48571Z","time spent":"1.913480657s","remote":"127.0.0.1:53986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:18.401493Z","caller":"traceutil/trace.go:171","msg":"trace[738613079] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"1.913061618s","start":"2026-07-17T18:07:16.488419Z","end":"2026-07-17T18:07:18.40148Z","steps":["trace[738613079] 'process raft request' (duration: 1.912948904s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:18.401558Z","caller":"traceutil/trace.go:171","msg":"trace[915797779] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"1.914418341s","start":"2026-07-17T18:07:16.487109Z","end":"2026-07-17T18:07:18.401527Z","steps":["trace[915797779] 'process raft request' (duration: 1.914148682s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:18.401873Z","caller":"traceutil/trace.go:171","msg":"trace[875031928] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"1.911140878s","start":"2026-07-17T18:07:16.490702Z","end":"2026-07-17T18:07:18.401843Z","steps":["trace[875031928] 'process raft request' (duration: 1.910762955s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.402032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.487088Z","time spent":"1.914879066s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:18.401594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.488404Z","time spent":"1.913158611s","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:18.401562Z","caller":"traceutil/trace.go:171","msg":"trace[1902552801] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:43; }","duration":"1.91468238s","start":"2026-07-17T18:07:16.486855Z","end":"2026-07-17T18:07:18.401537Z","steps":["trace[1902552801] 'read index received' (duration: 1.910772499s)","trace[1902552801] 'applied index is now lower than readState.Index' (duration: 3.908711ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:07:18.401687Z","caller":"traceutil/trace.go:171","msg":"trace[1125516388] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"1.911238701s","start":"2026-07-17T18:07:16.490413Z","end":"2026-07-17T18:07:18.401652Z","steps":["trace[1125516388] 'process raft request' (duration: 1.910997453s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.40258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.490404Z","time spent":"1.912144731s","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:18.401838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914949079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-07-17T18:07:18.402678Z","caller":"traceutil/trace.go:171","msg":"trace[551418447] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:44; }","duration":"1.915840258s","start":"2026-07-17T18:07:16.486811Z","end":"2026-07-17T18:07:18.402651Z","steps":["trace[551418447] 'agreement among raft nodes before linearized reading' (duration: 1.914808434s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.402769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.486791Z","time spent":"1.915951842s","remote":"127.0.0.1:53980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-17T18:07:18.402773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234991713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-17T18:07:18.402818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.859945573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-17T18:07:18.402806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.149234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:18.402896Z","caller":"traceutil/trace.go:171","msg":"trace[727379056] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:44; }","duration":"1.860023696s","start":"2026-07-17T18:07:16.542859Z","end":"2026-07-17T18:07:18.402883Z","steps":["trace[727379056] 'agreement among raft nodes before linearized reading' (duration: 1.859924503s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.402911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915477207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-17T18:07:18.402938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.542796Z","time spent":"1.860134409s","remote":"127.0.0.1:53920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-07-17T18:07:18.402945Z","caller":"traceutil/trace.go:171","msg":"trace[2103982842] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:44; }","duration":"1.915522808s","start":"2026-07-17T18:07:16.48741Z","end":"2026-07-17T18:07:18.402932Z","steps":["trace[2103982842] 'agreement among raft nodes before linearized reading' (duration: 1.915482777s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:18.402914Z","caller":"traceutil/trace.go:171","msg":"trace[365069487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:44; }","duration":"1.235175149s","start":"2026-07-17T18:07:17.16769Z","end":"2026-07-17T18:07:18.402866Z","steps":["trace[365069487] 'agreement among raft nodes before linearized reading' (duration: 1.234986373s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.40303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:17.167672Z","time spent":"1.235347414s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T18:07:18.402977Z","caller":"traceutil/trace.go:171","msg":"trace[846373365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"371.297458ms","start":"2026-07-17T18:07:18.031598Z","end":"2026-07-17T18:07:18.402896Z","steps":["trace[846373365] 'agreement among raft nodes before linearized reading' (duration: 371.131043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.403108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:18.031569Z","time spent":"371.522886ms","remote":"127.0.0.1:53816","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-07-17T18:07:18.402387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.490697Z","time spent":"1.911293673s","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:07:18.402845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.6989643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:18.403598Z","caller":"traceutil/trace.go:171","msg":"trace[524493018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"1.699759957s","start":"2026-07-17T18:07:16.703821Z","end":"2026-07-17T18:07:18.403581Z","steps":["trace[524493018] 'agreement among raft nodes before linearized reading' (duration: 1.698989851s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.403662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.703802Z","time spent":"1.69984335s","remote":"127.0.0.1:53816","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-07-17T18:07:18.402981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.487399Z","time spent":"1.91557469s","remote":"127.0.0.1:53902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" "} {"level":"warn","ts":"2026-07-17T18:07:18.403868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915304261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T18:07:18.403956Z","caller":"traceutil/trace.go:171","msg":"trace[1286227708] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:44; }","duration":"1.916639666s","start":"2026-07-17T18:07:16.487259Z","end":"2026-07-17T18:07:18.403899Z","steps":["trace[1286227708] 'agreement among raft nodes before linearized reading' (duration: 1.915337252s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:18.404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:16.486559Z","time spent":"1.917429161s","remote":"127.0.0.1:54186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-07-17T18:07:53.013415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122668927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T18:07:53.038088Z","caller":"traceutil/trace.go:171","msg":"trace[605651603] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"832.27599ms","start":"2026-07-17T18:07:52.205783Z","end":"2026-07-17T18:07:53.038059Z","steps":["trace[605651603] 'process raft request' (duration: 832.110405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.038256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.205764Z","time spent":"832.415375ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:53.108827Z","caller":"traceutil/trace.go:171","msg":"trace[139967380] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"895.085562ms","start":"2026-07-17T18:07:52.213711Z","end":"2026-07-17T18:07:53.108796Z","steps":["trace[139967380] 'process raft request' (duration: 894.820923ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:53.108898Z","caller":"traceutil/trace.go:171","msg":"trace[2111504591] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"892.014224ms","start":"2026-07-17T18:07:52.216859Z","end":"2026-07-17T18:07:53.108873Z","steps":["trace[2111504591] 'process raft request' (duration: 891.809288ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:53.108879Z","caller":"traceutil/trace.go:171","msg":"trace[542605719] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"893.717999ms","start":"2026-07-17T18:07:52.215118Z","end":"2026-07-17T18:07:53.108836Z","steps":["trace[542605719] 'process raft request' (duration: 893.484902ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:53.108946Z","caller":"traceutil/trace.go:171","msg":"trace[247297575] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"900.236006ms","start":"2026-07-17T18:07:52.208678Z","end":"2026-07-17T18:07:53.108914Z","steps":["trace[247297575] 'process raft request' (duration: 899.697189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.108982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.213693Z","time spent":"895.213016ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:53.108988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.216843Z","time spent":"892.103377ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:53.10904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.215112Z","time spent":"893.855563ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:53.109072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.208663Z","time spent":"900.345229ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:53.10906Z","caller":"traceutil/trace.go:171","msg":"trace[791111575] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"889.547565ms","start":"2026-07-17T18:07:52.219453Z","end":"2026-07-17T18:07:53.109001Z","steps":["trace[791111575] 'process raft request' (duration: 889.263216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.109227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.219445Z","time spent":"889.69443ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:53.10939Z","caller":"traceutil/trace.go:171","msg":"trace[822022774] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"889.876406ms","start":"2026-07-17T18:07:52.219499Z","end":"2026-07-17T18:07:53.109375Z","steps":["trace[822022774] 'process raft request' (duration: 889.69859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.109456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.219496Z","time spent":"889.932708ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:53.10948Z","caller":"traceutil/trace.go:171","msg":"trace[1334921228] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"889.899947ms","start":"2026-07-17T18:07:52.219563Z","end":"2026-07-17T18:07:53.109463Z","steps":["trace[1334921228] 'process raft request' (duration: 889.705361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.109567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.219559Z","time spent":"889.967209ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:53.109607Z","caller":"traceutil/trace.go:171","msg":"trace[1126397213] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"884.430282ms","start":"2026-07-17T18:07:52.225161Z","end":"2026-07-17T18:07:53.109592Z","steps":["trace[1126397213] 'process raft request' (duration: 884.195435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.109648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.225155Z","time spent":"884.473593ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:53.109688Z","caller":"traceutil/trace.go:171","msg":"trace[1551921238] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"878.07076ms","start":"2026-07-17T18:07:52.231604Z","end":"2026-07-17T18:07:53.109675Z","steps":["trace[1551921238] 'process raft request' (duration: 877.859003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.109749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.231598Z","time spent":"878.126171ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:53.109827Z","caller":"traceutil/trace.go:171","msg":"trace[450373801] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"862.347108ms","start":"2026-07-17T18:07:52.247471Z","end":"2026-07-17T18:07:53.109818Z","steps":["trace[450373801] 'process raft request' (duration: 862.143102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.109869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.247461Z","time spent":"862.384569ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:53.109921Z","caller":"traceutil/trace.go:171","msg":"trace[112229088] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:540; }","duration":"597.150565ms","start":"2026-07-17T18:07:52.512755Z","end":"2026-07-17T18:07:53.109905Z","steps":["trace[112229088] 'read index received' (duration: 525.206322ms)","trace[112229088] 'applied index is now lower than readState.Index' (duration: 71.942763ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:07:53.109924Z","caller":"traceutil/trace.go:171","msg":"trace[1520100302] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"732.419177ms","start":"2026-07-17T18:07:52.37749Z","end":"2026-07-17T18:07:53.109909Z","steps":["trace[1520100302] 'process raft request' (duration: 732.300393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.109991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.37746Z","time spent":"732.50678ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:53.110046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.27883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:07:53.110142Z","caller":"traceutil/trace.go:171","msg":"trace[574312046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"597.407303ms","start":"2026-07-17T18:07:52.512723Z","end":"2026-07-17T18:07:53.110131Z","steps":["trace[574312046] 'agreement among raft nodes before linearized reading' (duration: 597.259788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.110211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.512709Z","time spent":"597.492376ms","remote":"127.0.0.1:53816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T18:07:53.265388Z","caller":"traceutil/trace.go:171","msg":"trace[247371901] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:552; }","duration":"155.407273ms","start":"2026-07-17T18:07:53.109955Z","end":"2026-07-17T18:07:53.265362Z","steps":["trace[247371901] 'read index received' (duration: 111.090521ms)","trace[247371901] 'applied index is now lower than readState.Index' (duration: 44.315892ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:07:53.265536Z","caller":"traceutil/trace.go:171","msg":"trace[1735890969] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"218.733773ms","start":"2026-07-17T18:07:53.046765Z","end":"2026-07-17T18:07:53.265499Z","steps":["trace[1735890969] 'process raft request' (duration: 218.524236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.265663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.427023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T18:07:53.26582Z","caller":"traceutil/trace.go:171","msg":"trace[968355849] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"222.576925ms","start":"2026-07-17T18:07:53.043229Z","end":"2026-07-17T18:07:53.265806Z","steps":["trace[968355849] 'process raft request' (duration: 177.725665ms)","trace[968355849] 'compare' (duration: 44.057624ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:07:53.265857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.687959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T18:07:53.26589Z","caller":"traceutil/trace.go:171","msg":"trace[2026535095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"664.761701ms","start":"2026-07-17T18:07:52.601119Z","end":"2026-07-17T18:07:53.265881Z","steps":["trace[2026535095] 'agreement among raft nodes before linearized reading' (duration: 664.683558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.265931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.6011Z","time spent":"664.823392ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T18:07:53.265884Z","caller":"traceutil/trace.go:171","msg":"trace[485267079] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"219.60939ms","start":"2026-07-17T18:07:53.046255Z","end":"2026-07-17T18:07:53.265865Z","steps":["trace[485267079] 'process raft request' (duration: 218.96557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.266107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.317163ms","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-07-17T18:07:53.266178Z","caller":"traceutil/trace.go:171","msg":"trace[996772852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"497.448377ms","start":"2026-07-17T18:07:52.768714Z","end":"2026-07-17T18:07:53.266162Z","steps":["trace[996772852] 'agreement among raft nodes before linearized reading' (duration: 497.040284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:53.266284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:52.768695Z","time spent":"497.553211ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T18:07:53.266388Z","caller":"traceutil/trace.go:171","msg":"trace[107802998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"298.588158ms","start":"2026-07-17T18:07:52.967149Z","end":"2026-07-17T18:07:53.265737Z","steps":["trace[107802998] 'agreement among raft nodes before linearized reading' (duration: 298.33837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.216105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.400263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:07:54.216604Z","caller":"traceutil/trace.go:171","msg":"trace[530708673] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.092747844s","start":"2026-07-17T18:07:53.123815Z","end":"2026-07-17T18:07:54.216562Z","steps":["trace[530708673] 'process raft request' (duration: 1.092456745s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.216784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.123795Z","time spent":"1.092910658s","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:54.216802Z","caller":"traceutil/trace.go:171","msg":"trace[396284149] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.090660367s","start":"2026-07-17T18:07:53.1261Z","end":"2026-07-17T18:07:54.216761Z","steps":["trace[396284149] 'process raft request' (duration: 1.090208363s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:54.217378Z","caller":"traceutil/trace.go:171","msg":"trace[465262990] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.045614571s","start":"2026-07-17T18:07:53.171748Z","end":"2026-07-17T18:07:54.217362Z","steps":["trace[465262990] 'process raft request' (duration: 1.045087605s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:07:54.217041Z","caller":"traceutil/trace.go:171","msg":"trace[235317757] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.097383851s","start":"2026-07-17T18:07:53.119636Z","end":"2026-07-17T18:07:54.217019Z","steps":["trace[235317757] 'process raft request' (duration: 578.982537ms)","trace[235317757] 'compare' (duration: 517.244027ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:07:54.217737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.119613Z","time spent":"1.098044463s","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:54.21704Z","caller":"traceutil/trace.go:171","msg":"trace[575522623] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.097159974s","start":"2026-07-17T18:07:53.119746Z","end":"2026-07-17T18:07:54.216906Z","steps":["trace[575522623] 'process raft request' (duration: 1.096474062s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.217852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.119741Z","time spent":"1.098065623s","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:54.217126Z","caller":"traceutil/trace.go:171","msg":"trace[72542645] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.086473933s","start":"2026-07-17T18:07:53.130639Z","end":"2026-07-17T18:07:54.217113Z","steps":["trace[72542645] 'process raft request' (duration: 1.085930726s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.218136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.130616Z","time spent":"1.087374602s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:07:54.217134Z","caller":"traceutil/trace.go:171","msg":"trace[1322548025] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.089856712s","start":"2026-07-17T18:07:53.127263Z","end":"2026-07-17T18:07:54.21712Z","steps":["trace[1322548025] 'process raft request' (duration: 1.08916735s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.218544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.127253Z","time spent":"1.091157353s","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:07:54.217124Z","caller":"traceutil/trace.go:171","msg":"trace[2141267195] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.082487336s","start":"2026-07-17T18:07:53.134608Z","end":"2026-07-17T18:07:54.217095Z","steps":["trace[2141267195] 'process raft request' (duration: 1.082180856s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.21872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.134602Z","time spent":"1.084070227s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:07:54.217194Z","caller":"traceutil/trace.go:171","msg":"trace[361225482] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:555; }","duration":"951.711678ms","start":"2026-07-17T18:07:53.265458Z","end":"2026-07-17T18:07:54.21717Z","steps":["trace[361225482] 'read index received' (duration: 433.345865ms)","trace[361225482] 'applied index is now lower than readState.Index' (duration: 518.363393ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:07:54.217198Z","caller":"traceutil/trace.go:171","msg":"trace[477395721] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.083312502s","start":"2026-07-17T18:07:53.133858Z","end":"2026-07-17T18:07:54.21717Z","steps":["trace[477395721] 'process raft request' (duration: 1.082838847s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.218981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.133831Z","time spent":"1.08510416s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:07:54.217298Z","caller":"traceutil/trace.go:171","msg":"trace[535674955] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"940.694287ms","start":"2026-07-17T18:07:53.276589Z","end":"2026-07-17T18:07:54.217284Z","steps":["trace[535674955] 'process raft request' (duration: 940.419358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.219156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.586009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T18:07:54.21922Z","caller":"traceutil/trace.go:171","msg":"trace[1211756687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"256.697093ms","start":"2026-07-17T18:07:53.962504Z","end":"2026-07-17T18:07:54.219201Z","steps":["trace[1211756687] 'agreement among raft nodes before linearized reading' (duration: 256.517127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.219228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.276585Z","time spent":"942.601368ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:07:54.217237Z","caller":"traceutil/trace.go:171","msg":"trace[1263974640] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"942.661519ms","start":"2026-07-17T18:07:53.274482Z","end":"2026-07-17T18:07:54.217143Z","steps":["trace[1263974640] 'process raft request' (duration: 942.402521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.219538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.274451Z","time spent":"944.971183ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:07:54.217297Z","caller":"traceutil/trace.go:171","msg":"trace[227461736] transaction","detail":"{read_only:false; number_of_response:1; response_revision:549; }","duration":"940.221052ms","start":"2026-07-17T18:07:53.277053Z","end":"2026-07-17T18:07:54.217274Z","steps":["trace[227461736] 'process raft request' (duration: 939.985864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.219699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.277047Z","time spent":"942.613258ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:07:54.217322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104832478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:07:54.219954Z","caller":"traceutil/trace.go:171","msg":"trace[1949950672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"1.107503603s","start":"2026-07-17T18:07:53.112431Z","end":"2026-07-17T18:07:54.219935Z","steps":["trace[1949950672] 'agreement among raft nodes before linearized reading' (duration: 1.104796417s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.220008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.112415Z","time spent":"1.107579826s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T18:07:54.217339Z","caller":"traceutil/trace.go:171","msg":"trace[1565829911] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"940.841251ms","start":"2026-07-17T18:07:53.276487Z","end":"2026-07-17T18:07:54.217328Z","steps":["trace[1565829911] 'process raft request' (duration: 940.439448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.220188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.276468Z","time spent":"943.658581ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:07:54.217486Z","caller":"traceutil/trace.go:171","msg":"trace[1777350092] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"932.006ms","start":"2026-07-17T18:07:53.285455Z","end":"2026-07-17T18:07:54.217461Z","steps":["trace[1777350092] 'process raft request' (duration: 931.645998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:07:54.220366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.285443Z","time spent":"934.880431ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:07:54.21751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.171709Z","time spent":"1.045746515s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:07:54.22568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:07:53.126088Z","time spent":"1.092087722s","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:08:00.609843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.083827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:08:00.609928Z","caller":"traceutil/trace.go:171","msg":"trace[1764909384] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"407.636254ms","start":"2026-07-17T18:08:00.202278Z","end":"2026-07-17T18:08:00.609914Z","steps":["trace[1764909384] 'read index received' (duration: 21.474644ms)","trace[1764909384] 'applied index is now lower than readState.Index' (duration: 386.16079ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:08:00.609989Z","caller":"traceutil/trace.go:171","msg":"trace[87522670] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"441.724201ms","start":"2026-07-17T18:08:00.168258Z","end":"2026-07-17T18:08:00.609982Z","steps":["trace[87522670] 'process raft request' (duration: 55.444778ms)","trace[87522670] 'compare' (duration: 385.749236ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:08:00.610026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:00.168234Z","time spent":"441.775803ms","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:08:00.610256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.977275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mf48s\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-17T18:08:00.610277Z","caller":"traceutil/trace.go:171","msg":"trace[402338249] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mf48s; range_end:; response_count:1; response_revision:621; }","duration":"408.002276ms","start":"2026-07-17T18:08:00.202268Z","end":"2026-07-17T18:08:00.61027Z","steps":["trace[402338249] 'agreement among raft nodes before linearized reading' (duration: 407.883663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:00.610297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:00.20219Z","time spent":"408.102769ms","remote":"127.0.0.1:53986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-mf48s\" "} {"level":"warn","ts":"2026-07-17T18:08:00.610442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.209431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T18:08:00.610465Z","caller":"traceutil/trace.go:171","msg":"trace[666086865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"359.254472ms","start":"2026-07-17T18:08:00.251205Z","end":"2026-07-17T18:08:00.610459Z","steps":["trace[666086865] 'agreement among raft nodes before linearized reading' (duration: 359.19213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:00.610482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:00.251192Z","time spent":"359.286013ms","remote":"127.0.0.1:54076","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-07-17T18:08:00.610593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.994924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T18:08:00.61061Z","caller":"traceutil/trace.go:171","msg":"trace[1777410306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"359.051165ms","start":"2026-07-17T18:08:00.251553Z","end":"2026-07-17T18:08:00.610604Z","steps":["trace[1777410306] 'agreement among raft nodes before linearized reading' (duration: 359.003504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:00.610626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:00.251531Z","time spent":"359.091707ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T18:08:00.610743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.078636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T18:08:00.610837Z","caller":"traceutil/trace.go:171","msg":"trace[267358498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"359.200731ms","start":"2026-07-17T18:08:00.251616Z","end":"2026-07-17T18:08:00.610817Z","steps":["trace[267358498] 'agreement among raft nodes before linearized reading' (duration: 358.939252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:00.610882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:00.251609Z","time spent":"359.264213ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T18:08:00.611164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.158497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T18:08:00.611308Z","caller":"traceutil/trace.go:171","msg":"trace[1658916340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"358.318012ms","start":"2026-07-17T18:08:00.252939Z","end":"2026-07-17T18:08:00.611257Z","steps":["trace[1658916340] 'agreement among raft nodes before linearized reading' (duration: 357.677831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:00.611358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:00.252928Z","time spent":"358.419156ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T18:08:02.013942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122669148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:08:02.142604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.598958ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:08:02.142733Z","caller":"traceutil/trace.go:171","msg":"trace[167996828] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:626; }","duration":"788.732742ms","start":"2026-07-17T18:08:01.353977Z","end":"2026-07-17T18:08:02.14271Z","steps":["trace[167996828] 'range keys from in-memory index tree' (duration: 788.580428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:02.515114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122669148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:08:03.015943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122669148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:08:03.035238Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.010089019s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T18:08:03.036335Z","caller":"traceutil/trace.go:171","msg":"trace[1214207534] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.937514953s","start":"2026-07-17T18:08:01.098798Z","end":"2026-07-17T18:08:03.036313Z","steps":["trace[1214207534] 'process raft request' (duration: 1.936851742s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:03.036459Z","caller":"traceutil/trace.go:171","msg":"trace[1276149963] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"1.522968528s","start":"2026-07-17T18:08:01.51347Z","end":"2026-07-17T18:08:03.036438Z","steps":["trace[1276149963] 'read index received' (duration: 1.521947925s)","trace[1276149963] 'applied index is now lower than readState.Index' (duration: 1.019173ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:08:03.036478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:01.098771Z","time spent":"1.937625305s","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:08:03.036551Z","caller":"traceutil/trace.go:171","msg":"trace[1943671858] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.030441308s","start":"2026-07-17T18:08:02.006085Z","end":"2026-07-17T18:08:03.036526Z","steps":["trace[1943671858] 'process raft request' (duration: 1.030169319s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:03.036713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.176245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T18:08:03.036741Z","caller":"traceutil/trace.go:171","msg":"trace[1100752148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"405.239628ms","start":"2026-07-17T18:08:02.631493Z","end":"2026-07-17T18:08:03.036732Z","steps":["trace[1100752148] 'agreement among raft nodes before linearized reading' (duration: 405.144765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:03.036772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:02.631474Z","time spent":"405.292799ms","remote":"127.0.0.1:54076","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-07-17T18:08:03.03678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:02.006055Z","time spent":"1.030597183s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:08:03.036617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523136684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T18:08:03.036918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.986455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T18:08:03.036923Z","caller":"traceutil/trace.go:171","msg":"trace[1264575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.523496025s","start":"2026-07-17T18:08:01.513413Z","end":"2026-07-17T18:08:03.036909Z","steps":["trace[1264575] 'agreement among raft nodes before linearized reading' (duration: 1.523093542s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:03.036941Z","caller":"traceutil/trace.go:171","msg":"trace[1199774030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"344.044458ms","start":"2026-07-17T18:08:02.692891Z","end":"2026-07-17T18:08:03.036935Z","steps":["trace[1199774030] 'agreement among raft nodes before linearized reading' (duration: 344.001656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:03.036961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:02.69287Z","time spent":"344.087648ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T18:08:03.036972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:01.513393Z","time spent":"1.523565837s","remote":"127.0.0.1:53816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T18:08:03.036882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.81436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T18:08:03.037085Z","caller":"traceutil/trace.go:171","msg":"trace[167433379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"344.014526ms","start":"2026-07-17T18:08:02.693064Z","end":"2026-07-17T18:08:03.037078Z","steps":["trace[167433379] 'agreement among raft nodes before linearized reading' (duration: 343.787589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:03.037079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.447312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T18:08:03.037153Z","caller":"traceutil/trace.go:171","msg":"trace[549938535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"345.552185ms","start":"2026-07-17T18:08:02.691588Z","end":"2026-07-17T18:08:03.03714Z","steps":["trace[549938535] 'agreement among raft nodes before linearized reading' (duration: 345.325588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:03.03722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:02.69156Z","time spent":"345.623537ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T18:08:03.037109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:02.693018Z","time spent":"344.081799ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T18:08:04.6947Z","caller":"traceutil/trace.go:171","msg":"trace[595933177] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"379.666363ms","start":"2026-07-17T18:08:04.315011Z","end":"2026-07-17T18:08:04.694677Z","steps":["trace[595933177] 'process raft request' (duration: 379.632092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:04.694977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:04.315005Z","time spent":"379.911641ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:08:04.695158Z","caller":"traceutil/trace.go:171","msg":"trace[1776959406] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"380.182059ms","start":"2026-07-17T18:08:04.314817Z","end":"2026-07-17T18:08:04.694999Z","steps":["trace[1776959406] 'process raft request' (duration: 379.788647ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:04.69509Z","caller":"traceutil/trace.go:171","msg":"trace[544611296] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"380.346504ms","start":"2026-07-17T18:08:04.314716Z","end":"2026-07-17T18:08:04.695063Z","steps":["trace[544611296] 'process raft request' (duration: 379.774406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:04.695489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:04.314697Z","time spent":"380.690046ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:08:04.695625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:04.314809Z","time spent":"380.430847ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:08:04.707334Z","caller":"traceutil/trace.go:171","msg":"trace[1115958749] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"384.8963ms","start":"2026-07-17T18:08:04.322415Z","end":"2026-07-17T18:08:04.707311Z","steps":["trace[1115958749] 'process raft request' (duration: 384.611801ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:04.707377Z","caller":"traceutil/trace.go:171","msg":"trace[779900159] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"383.378011ms","start":"2026-07-17T18:08:04.323982Z","end":"2026-07-17T18:08:04.70736Z","steps":["trace[779900159] 'process raft request' (duration: 383.238547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:04.707442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:04.322405Z","time spent":"384.989722ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T18:08:04.707489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:04.323973Z","time spent":"383.449273ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:08:04.707517Z","caller":"traceutil/trace.go:171","msg":"trace[1021859526] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"383.489165ms","start":"2026-07-17T18:08:04.323989Z","end":"2026-07-17T18:08:04.707478Z","steps":["trace[1021859526] 'process raft request' (duration: 383.31702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:04.707666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:04.323974Z","time spent":"383.617299ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:08:04.710143Z","caller":"traceutil/trace.go:171","msg":"trace[1753004342] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"198.399844ms","start":"2026-07-17T18:08:04.511731Z","end":"2026-07-17T18:08:04.71013Z","steps":["trace[1753004342] 'process raft request' (duration: 198.013332ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:04.710376Z","caller":"traceutil/trace.go:171","msg":"trace[925874517] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:673; }","duration":"197.354161ms","start":"2026-07-17T18:08:04.513014Z","end":"2026-07-17T18:08:04.710368Z","steps":["trace[925874517] 'read index received' (duration: 181.525457ms)","trace[925874517] 'applied index is now lower than readState.Index' (duration: 15.827494ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:08:04.710456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.436123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:08:04.710479Z","caller":"traceutil/trace.go:171","msg":"trace[287994444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"197.506326ms","start":"2026-07-17T18:08:04.512966Z","end":"2026-07-17T18:08:04.710472Z","steps":["trace[287994444] 'agreement among raft nodes before linearized reading' (duration: 197.433214ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:04.711403Z","caller":"traceutil/trace.go:171","msg":"trace[1984350208] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"197.32798ms","start":"2026-07-17T18:08:04.513989Z","end":"2026-07-17T18:08:04.711317Z","steps":["trace[1984350208] 'process raft request' (duration: 196.560426ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:14.596114Z","caller":"traceutil/trace.go:171","msg":"trace[1295271610] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"281.276226ms","start":"2026-07-17T18:08:14.314816Z","end":"2026-07-17T18:08:14.596092Z","steps":["trace[1295271610] 'process raft request' (duration: 279.624253ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:14.598686Z","caller":"traceutil/trace.go:171","msg":"trace[1472429087] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"283.86737ms","start":"2026-07-17T18:08:14.314806Z","end":"2026-07-17T18:08:14.598673Z","steps":["trace[1472429087] 'process raft request' (duration: 282.591709ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:14.598965Z","caller":"traceutil/trace.go:171","msg":"trace[1593660893] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"284.007214ms","start":"2026-07-17T18:08:14.314952Z","end":"2026-07-17T18:08:14.598959Z","steps":["trace[1593660893] 'process raft request' (duration: 282.514886ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:17.619219Z","caller":"traceutil/trace.go:171","msg":"trace[46539680] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"498.601283ms","start":"2026-07-17T18:08:17.120594Z","end":"2026-07-17T18:08:17.619195Z","steps":["trace[46539680] 'read index received' (duration: 498.480569ms)","trace[46539680] 'applied index is now lower than readState.Index' (duration: 119.584µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:08:17.619275Z","caller":"traceutil/trace.go:171","msg":"trace[1450598482] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"499.79024ms","start":"2026-07-17T18:08:17.119442Z","end":"2026-07-17T18:08:17.619232Z","steps":["trace[1450598482] 'process raft request' (duration: 499.616435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:17.619456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:17.119427Z","time spent":"499.931246ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:08:17.619472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.851311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T18:08:17.619507Z","caller":"traceutil/trace.go:171","msg":"trace[877636390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"498.906823ms","start":"2026-07-17T18:08:17.12059Z","end":"2026-07-17T18:08:17.619497Z","steps":["trace[877636390] 'agreement among raft nodes before linearized reading' (duration: 498.761978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:17.619543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:17.120575Z","time spent":"498.960034ms","remote":"127.0.0.1:54076","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-07-17T18:08:17.620069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.424632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T18:08:17.620111Z","caller":"traceutil/trace.go:171","msg":"trace[1663968628] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"498.008904ms","start":"2026-07-17T18:08:17.12209Z","end":"2026-07-17T18:08:17.620099Z","steps":["trace[1663968628] 'process raft request' (duration: 497.692914ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:17.620135Z","caller":"traceutil/trace.go:171","msg":"trace[474500442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"495.519625ms","start":"2026-07-17T18:08:17.124593Z","end":"2026-07-17T18:08:17.620113Z","steps":["trace[474500442] 'agreement among raft nodes before linearized reading' (duration: 495.352119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:17.620182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:17.124576Z","time spent":"495.593987ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T18:08:17.620207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:17.122074Z","time spent":"498.067555ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:08:17.62047Z","caller":"traceutil/trace.go:171","msg":"trace[486184238] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"311.176519ms","start":"2026-07-17T18:08:17.309282Z","end":"2026-07-17T18:08:17.620459Z","steps":["trace[486184238] 'process raft request' (duration: 310.59848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:17.620427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.144023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:08:17.620512Z","caller":"traceutil/trace.go:171","msg":"trace[958106373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"106.269308ms","start":"2026-07-17T18:08:17.51423Z","end":"2026-07-17T18:08:17.6205Z","steps":["trace[958106373] 'agreement among raft nodes before linearized reading' (duration: 106.160084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:17.620531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:17.309258Z","time spent":"311.237581ms","remote":"127.0.0.1:54054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:08:53.277816Z","caller":"traceutil/trace.go:171","msg":"trace[127589812] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"226.604909ms","start":"2026-07-17T18:08:53.051186Z","end":"2026-07-17T18:08:53.277791Z","steps":["trace[127589812] 'read index received' (duration: 226.504425ms)","trace[127589812] 'applied index is now lower than readState.Index' (duration: 99.334µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:08:53.277858Z","caller":"traceutil/trace.go:171","msg":"trace[482807552] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"316.153731ms","start":"2026-07-17T18:08:52.961662Z","end":"2026-07-17T18:08:53.277816Z","steps":["trace[482807552] 'process raft request' (duration: 315.983736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:53.277942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.737832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:08:53.277984Z","caller":"traceutil/trace.go:171","msg":"trace[1940259022] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1014; }","duration":"226.841575ms","start":"2026-07-17T18:08:53.051131Z","end":"2026-07-17T18:08:53.277972Z","steps":["trace[1940259022] 'agreement among raft nodes before linearized reading' (duration: 226.756453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:53.277984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:52.961634Z","time spent":"316.290825ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:08:53.315508Z","caller":"traceutil/trace.go:171","msg":"trace[657792579] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"151.183591ms","start":"2026-07-17T18:08:53.164303Z","end":"2026-07-17T18:08:53.315487Z","steps":["trace[657792579] 'process raft request' (duration: 151.035607ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:08:54.105294Z","caller":"traceutil/trace.go:171","msg":"trace[1751874156] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"318.448353ms","start":"2026-07-17T18:08:53.786827Z","end":"2026-07-17T18:08:54.105275Z","steps":["trace[1751874156] 'read index received' (duration: 318.34937ms)","trace[1751874156] 'applied index is now lower than readState.Index' (duration: 98.283µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:08:54.105397Z","caller":"traceutil/trace.go:171","msg":"trace[1120487486] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"320.790808ms","start":"2026-07-17T18:08:53.784572Z","end":"2026-07-17T18:08:54.105363Z","steps":["trace[1120487486] 'process raft request' (duration: 320.593822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:54.10548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.64783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T18:08:54.105509Z","caller":"traceutil/trace.go:171","msg":"trace[276508713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"318.723842ms","start":"2026-07-17T18:08:53.786776Z","end":"2026-07-17T18:08:54.1055Z","steps":["trace[276508713] 'agreement among raft nodes before linearized reading' (duration: 318.612199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:54.105544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:53.786751Z","time spent":"318.784455ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T18:08:54.105574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:53.784557Z","time spent":"320.913742ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:08:54.105616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.275426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T18:08:54.105667Z","caller":"traceutil/trace.go:171","msg":"trace[712061000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"316.348167ms","start":"2026-07-17T18:08:53.789305Z","end":"2026-07-17T18:08:54.105653Z","steps":["trace[712061000] 'agreement among raft nodes before linearized reading' (duration: 316.137111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:54.105701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:53.789297Z","time spent":"316.397059ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T18:08:54.105709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.309306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T18:08:54.105791Z","caller":"traceutil/trace.go:171","msg":"trace[1693243692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"316.42211ms","start":"2026-07-17T18:08:53.789351Z","end":"2026-07-17T18:08:54.105773Z","steps":["trace[1693243692] 'agreement among raft nodes before linearized reading' (duration: 316.126821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:08:54.105836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:08:53.789342Z","time spent":"316.484532ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T18:09:15.032976Z","caller":"traceutil/trace.go:171","msg":"trace[1365703650] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"270.683952ms","start":"2026-07-17T18:09:14.762236Z","end":"2026-07-17T18:09:15.03292Z","steps":["trace[1365703650] 'process raft request' (duration: 269.323179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:15.684903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.91901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:15.685481Z","caller":"traceutil/trace.go:171","msg":"trace[1218773893] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1086; }","duration":"424.561721ms","start":"2026-07-17T18:09:15.26089Z","end":"2026-07-17T18:09:15.685452Z","steps":["trace[1218773893] 'count revisions from in-memory index tree' (duration: 423.814416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:15.685547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:15.260846Z","time spent":"424.679854ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:09:15.685568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.414961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T18:09:15.685676Z","caller":"traceutil/trace.go:171","msg":"trace[1237569083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"312.780304ms","start":"2026-07-17T18:09:15.372869Z","end":"2026-07-17T18:09:15.68565Z","steps":["trace[1237569083] 'range keys from in-memory index tree' (duration: 311.216955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:15.685751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:15.37285Z","time spent":"312.886918ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T18:09:15.696588Z","caller":"traceutil/trace.go:171","msg":"trace[372367025] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"322.564324ms","start":"2026-07-17T18:09:15.373997Z","end":"2026-07-17T18:09:15.696561Z","steps":["trace[372367025] 'process raft request' (duration: 322.386939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:15.696772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:15.373981Z","time spent":"322.689188ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:16.654937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.927729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:16.655017Z","caller":"traceutil/trace.go:171","msg":"trace[473279021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"142.054303ms","start":"2026-07-17T18:09:16.512946Z","end":"2026-07-17T18:09:16.655Z","steps":["trace[473279021] 'range keys from in-memory index tree' (duration: 141.845407ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:18.135898Z","caller":"traceutil/trace.go:171","msg":"trace[1491156651] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"432.455591ms","start":"2026-07-17T18:09:17.703346Z","end":"2026-07-17T18:09:18.135802Z","steps":["trace[1491156651] 'read index received' (duration: 432.002317ms)","trace[1491156651] 'applied index is now lower than readState.Index' (duration: 452.404µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:18.13595Z","caller":"traceutil/trace.go:171","msg":"trace[369496767] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"433.69178ms","start":"2026-07-17T18:09:17.702214Z","end":"2026-07-17T18:09:18.135906Z","steps":["trace[369496767] 'process raft request' (duration: 433.213465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:18.136118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.74612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T18:09:18.136159Z","caller":"traceutil/trace.go:171","msg":"trace[1028182500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"432.874104ms","start":"2026-07-17T18:09:17.703271Z","end":"2026-07-17T18:09:18.136145Z","steps":["trace[1028182500] 'agreement among raft nodes before linearized reading' (duration: 432.708308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:18.136195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:17.703251Z","time spent":"432.936976ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T18:09:18.136187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:17.702195Z","time spent":"433.844804ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:18.630463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.49873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:09:18.63101Z","caller":"traceutil/trace.go:171","msg":"trace[1970667428] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"490.597548ms","start":"2026-07-17T18:09:18.14038Z","end":"2026-07-17T18:09:18.630978Z","steps":["trace[1970667428] 'process raft request' (duration: 162.490379ms)","trace[1970667428] 'compare' (duration: 327.364526ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:09:18.63116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:18.14035Z","time spent":"490.728182ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:09:18.631169Z","caller":"traceutil/trace.go:171","msg":"trace[248276640] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"392.840606ms","start":"2026-07-17T18:09:18.23804Z","end":"2026-07-17T18:09:18.630881Z","steps":["trace[248276640] 'read index received' (duration: 64.765698ms)","trace[248276640] 'applied index is now lower than readState.Index' (duration: 328.073288ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:09:18.631365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.330962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T18:09:18.631409Z","caller":"traceutil/trace.go:171","msg":"trace[711905970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1094; }","duration":"393.416094ms","start":"2026-07-17T18:09:18.237981Z","end":"2026-07-17T18:09:18.631397Z","steps":["trace[711905970] 'agreement among raft nodes before linearized reading' (duration: 393.113505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:18.631507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.462908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:18.63155Z","caller":"traceutil/trace.go:171","msg":"trace[308394068] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"118.53644ms","start":"2026-07-17T18:09:18.513002Z","end":"2026-07-17T18:09:18.631539Z","steps":["trace[308394068] 'agreement among raft nodes before linearized reading' (duration: 118.474158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:18.631498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:18.237957Z","time spent":"393.533678ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T18:09:18.631783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.519183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T18:09:18.631846Z","caller":"traceutil/trace.go:171","msg":"trace[2047587337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"274.618206ms","start":"2026-07-17T18:09:18.357212Z","end":"2026-07-17T18:09:18.63183Z","steps":["trace[2047587337] 'agreement among raft nodes before linearized reading' (duration: 274.41971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:18.631867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.596695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T18:09:18.631977Z","caller":"traceutil/trace.go:171","msg":"trace[777063140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"274.73754ms","start":"2026-07-17T18:09:18.357216Z","end":"2026-07-17T18:09:18.631953Z","steps":["trace[777063140] 'agreement among raft nodes before linearized reading' (duration: 274.438501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:18.632651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.615848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T18:09:18.632864Z","caller":"traceutil/trace.go:171","msg":"trace[259641544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1094; }","duration":"331.00911ms","start":"2026-07-17T18:09:18.301685Z","end":"2026-07-17T18:09:18.632694Z","steps":["trace[259641544] 'agreement among raft nodes before linearized reading' (duration: 329.684279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:18.633051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:18.301656Z","time spent":"331.333251ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T18:09:19.226374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.190183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:09:19.226858Z","caller":"traceutil/trace.go:171","msg":"trace[1588610775] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"591.329597ms","start":"2026-07-17T18:09:18.6355Z","end":"2026-07-17T18:09:19.226829Z","steps":["trace[1588610775] 'process raft request' (duration: 451.597486ms)","trace[1588610775] 'compare' (duration: 139.008548ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:19.226974Z","caller":"traceutil/trace.go:171","msg":"trace[1203627137] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"590.545211ms","start":"2026-07-17T18:09:18.636407Z","end":"2026-07-17T18:09:19.226952Z","steps":["trace[1203627137] 'process raft request' (duration: 590.325495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:19.227047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:18.635464Z","time spent":"591.464071ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:19.227086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:18.636395Z","time spent":"590.634165ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:09:19.227024Z","caller":"traceutil/trace.go:171","msg":"trace[1069820436] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"590.374546ms","start":"2026-07-17T18:09:18.636635Z","end":"2026-07-17T18:09:19.227009Z","steps":["trace[1069820436] 'process raft request' (duration: 590.291734ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:19.227174Z","caller":"traceutil/trace.go:171","msg":"trace[1122481457] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"590.142159ms","start":"2026-07-17T18:09:18.637009Z","end":"2026-07-17T18:09:19.227151Z","steps":["trace[1122481457] 'process raft request' (duration: 589.966883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:19.227226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:18.636623Z","time spent":"590.561982ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:19.227306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:18.636999Z","time spent":"590.222901ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:20.523448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.099031ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-17T18:09:20.523559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:19.314837Z","time spent":"1.208718126s","remote":"127.0.0.1:53842","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T18:09:21.158856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.667629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:09:21.158939Z","caller":"traceutil/trace.go:171","msg":"trace[618890753] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"647.866585ms","start":"2026-07-17T18:09:20.511059Z","end":"2026-07-17T18:09:21.158925Z","steps":["trace[618890753] 'read index received' (duration: 422.078282ms)","trace[618890753] 'applied index is now lower than readState.Index' (duration: 225.787303ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:21.15902Z","caller":"traceutil/trace.go:171","msg":"trace[1597006036] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"1.017482807s","start":"2026-07-17T18:09:20.141516Z","end":"2026-07-17T18:09:21.158999Z","steps":["trace[1597006036] 'process raft request' (duration: 791.605152ms)","trace[1597006036] 'compare' (duration: 225.410451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:09:21.159178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:20.141493Z","time spent":"1.017590311s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:21.159544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.129433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:21.159591Z","caller":"traceutil/trace.go:171","msg":"trace[1276410190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"648.536606ms","start":"2026-07-17T18:09:20.511043Z","end":"2026-07-17T18:09:21.159579Z","steps":["trace[1276410190] 'agreement among raft nodes before linearized reading' (duration: 648.111012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:21.159623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:20.51102Z","time spent":"648.594038ms","remote":"127.0.0.1:53816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T18:09:21.200737Z","caller":"traceutil/trace.go:171","msg":"trace[902034952] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"676.021045ms","start":"2026-07-17T18:09:20.524684Z","end":"2026-07-17T18:09:21.200705Z","steps":["trace[902034952] 'process raft request' (duration: 675.84144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:21.20093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:20.524659Z","time spent":"676.17181ms","remote":"127.0.0.1:53842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:21.230237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.531517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T18:09:21.230351Z","caller":"traceutil/trace.go:171","msg":"trace[1160761819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"594.690313ms","start":"2026-07-17T18:09:20.635647Z","end":"2026-07-17T18:09:21.230337Z","steps":["trace[1160761819] 'agreement among raft nodes before linearized reading' (duration: 594.503667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:21.230722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:20.635622Z","time spent":"595.091905ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T18:09:35.987713Z","caller":"traceutil/trace.go:171","msg":"trace[646077917] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"205.3142ms","start":"2026-07-17T18:09:35.782375Z","end":"2026-07-17T18:09:35.987689Z","steps":["trace[646077917] 'process raft request' (duration: 205.147024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:37.887893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122670426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:09:37.920865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409832013s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:37.920941Z","caller":"traceutil/trace.go:171","msg":"trace[1765462533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"1.409949718s","start":"2026-07-17T18:09:36.510972Z","end":"2026-07-17T18:09:37.920922Z","steps":["trace[1765462533] 'range keys from in-memory index tree' (duration: 1.409759612s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:37.920981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:36.510954Z","time spent":"1.410018889s","remote":"127.0.0.1:53816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T18:09:37.938153Z","caller":"traceutil/trace.go:171","msg":"trace[753483419] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"728.265197ms","start":"2026-07-17T18:09:37.209861Z","end":"2026-07-17T18:09:37.938126Z","steps":["trace[753483419] 'process raft request' (duration: 728.062481ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:37.938064Z","caller":"traceutil/trace.go:171","msg":"trace[766575612] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"551.19541ms","start":"2026-07-17T18:09:37.386846Z","end":"2026-07-17T18:09:37.938042Z","steps":["trace[766575612] 'read index received' (duration: 551.013585ms)","trace[766575612] 'applied index is now lower than readState.Index' (duration: 181.265µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:09:37.938297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:37.209836Z","time spent":"728.376941ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:37.938384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.521061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T18:09:37.938446Z","caller":"traceutil/trace.go:171","msg":"trace[454223498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1204; }","duration":"551.620524ms","start":"2026-07-17T18:09:37.386812Z","end":"2026-07-17T18:09:37.938432Z","steps":["trace[454223498] 'agreement among raft nodes before linearized reading' (duration: 551.415087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:37.938453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.814419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T18:09:37.938471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:37.386789Z","time spent":"551.676536ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T18:09:37.938481Z","caller":"traceutil/trace.go:171","msg":"trace[1158316474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"540.87093ms","start":"2026-07-17T18:09:37.397602Z","end":"2026-07-17T18:09:37.938473Z","steps":["trace[1158316474] 'agreement among raft nodes before linearized reading' (duration: 540.759947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:37.938596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.545785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:37.938619Z","caller":"traceutil/trace.go:171","msg":"trace[1126758724] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1204; }","duration":"163.605477ms","start":"2026-07-17T18:09:37.775007Z","end":"2026-07-17T18:09:37.938613Z","steps":["trace[1126758724] 'agreement among raft nodes before linearized reading' (duration: 163.560595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:37.938681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.629553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-17T18:09:37.938686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.931788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T18:09:37.938709Z","caller":"traceutil/trace.go:171","msg":"trace[478846702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"535.695435ms","start":"2026-07-17T18:09:37.403007Z","end":"2026-07-17T18:09:37.938702Z","steps":["trace[478846702] 'agreement among raft nodes before linearized reading' (duration: 535.620922ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:37.938714Z","caller":"traceutil/trace.go:171","msg":"trace[511219407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"538.97366ms","start":"2026-07-17T18:09:37.399734Z","end":"2026-07-17T18:09:37.938707Z","steps":["trace[511219407] 'agreement among raft nodes before linearized reading' (duration: 538.896807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:37.938725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:37.402986Z","time spent":"535.735066ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T18:09:37.938734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:37.399727Z","time spent":"539.00239ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T18:09:37.938801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.190709ms","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-07-17T18:09:37.938528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:37.397588Z","time spent":"540.932303ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T18:09:37.938816Z","caller":"traceutil/trace.go:171","msg":"trace[476141508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"535.22677ms","start":"2026-07-17T18:09:37.403584Z","end":"2026-07-17T18:09:37.938811Z","steps":["trace[476141508] 'agreement among raft nodes before linearized reading' (duration: 535.181829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:37.938834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:37.403572Z","time spent":"535.258181ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T18:09:52.272688Z","caller":"traceutil/trace.go:171","msg":"trace[289695422] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"274.867772ms","start":"2026-07-17T18:09:51.997802Z","end":"2026-07-17T18:09:52.272669Z","steps":["trace[289695422] 'read index received' (duration: 274.676655ms)","trace[289695422] 'applied index is now lower than readState.Index' (duration: 190.477µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:52.272823Z","caller":"traceutil/trace.go:171","msg":"trace[241952732] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"291.768962ms","start":"2026-07-17T18:09:51.981021Z","end":"2026-07-17T18:09:52.27279Z","steps":["trace[241952732] 'process raft request' (duration: 291.549755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:52.27288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.064568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T18:09:52.272912Z","caller":"traceutil/trace.go:171","msg":"trace[558621616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"275.13604ms","start":"2026-07-17T18:09:51.997766Z","end":"2026-07-17T18:09:52.272902Z","steps":["trace[558621616] 'agreement among raft nodes before linearized reading' (duration: 275.005066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:52.273039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.9218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T18:09:52.273064Z","caller":"traceutil/trace.go:171","msg":"trace[952644240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"267.973071ms","start":"2026-07-17T18:09:52.005085Z","end":"2026-07-17T18:09:52.273058Z","steps":["trace[952644240] 'agreement among raft nodes before linearized reading' (duration: 267.923039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:52.273259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.922248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T18:09:52.273282Z","caller":"traceutil/trace.go:171","msg":"trace[756976508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"261.9751ms","start":"2026-07-17T18:09:52.011301Z","end":"2026-07-17T18:09:52.273276Z","steps":["trace[756976508] 'agreement among raft nodes before linearized reading' (duration: 261.919888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:52.27332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.616503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T18:09:52.273344Z","caller":"traceutil/trace.go:171","msg":"trace[1909596519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"253.666125ms","start":"2026-07-17T18:09:52.01967Z","end":"2026-07-17T18:09:52.273336Z","steps":["trace[1909596519] 'agreement among raft nodes before linearized reading' (duration: 253.613473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:52.273254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.469782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T18:09:52.273453Z","caller":"traceutil/trace.go:171","msg":"trace[908520900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"260.688989ms","start":"2026-07-17T18:09:52.012743Z","end":"2026-07-17T18:09:52.273432Z","steps":["trace[908520900] 'agreement among raft nodes before linearized reading' (duration: 260.4124ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:54.464845Z","caller":"traceutil/trace.go:171","msg":"trace[1245382143] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"154.60715ms","start":"2026-07-17T18:09:54.310214Z","end":"2026-07-17T18:09:54.464822Z","steps":["trace[1245382143] 'read index received' (duration: 154.379213ms)","trace[1245382143] 'applied index is now lower than readState.Index' (duration: 226.877µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:54.464899Z","caller":"traceutil/trace.go:171","msg":"trace[1163413732] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"186.84412ms","start":"2026-07-17T18:09:54.278024Z","end":"2026-07-17T18:09:54.464868Z","steps":["trace[1163413732] 'process raft request' (duration: 186.653393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:54.465107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.58704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-17T18:09:54.465145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.615449ms","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-07-17T18:09:54.465237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.298991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-17T18:09:54.465282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.549579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T18:09:54.465313Z","caller":"traceutil/trace.go:171","msg":"trace[678806832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"129.429075ms","start":"2026-07-17T18:09:54.335868Z","end":"2026-07-17T18:09:54.465297Z","steps":["trace[678806832] 'agreement among raft nodes before linearized reading' (duration: 129.181427ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:54.465324Z","caller":"traceutil/trace.go:171","msg":"trace[3678233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"129.608711ms","start":"2026-07-17T18:09:54.335703Z","end":"2026-07-17T18:09:54.465311Z","steps":["trace[3678233] 'agreement among raft nodes before linearized reading' (duration: 129.497167ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:54.465252Z","caller":"traceutil/trace.go:171","msg":"trace[1457865002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"128.743423ms","start":"2026-07-17T18:09:54.336467Z","end":"2026-07-17T18:09:54.46521Z","steps":["trace[1457865002] 'agreement among raft nodes before linearized reading' (duration: 128.520656ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:54.46517Z","caller":"traceutil/trace.go:171","msg":"trace[1689769035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"129.905301ms","start":"2026-07-17T18:09:54.335247Z","end":"2026-07-17T18:09:54.465152Z","steps":["trace[1689769035] 'agreement among raft nodes before linearized reading' (duration: 129.721425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:54.465736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.520079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T18:09:54.465951Z","caller":"traceutil/trace.go:171","msg":"trace[219735847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1265; }","duration":"155.722095ms","start":"2026-07-17T18:09:54.310165Z","end":"2026-07-17T18:09:54.465887Z","steps":["trace[219735847] 'agreement among raft nodes before linearized reading' (duration: 155.111486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:54.759749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.561785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:09:54.760239Z","caller":"traceutil/trace.go:171","msg":"trace[626959812] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"289.697825ms","start":"2026-07-17T18:09:54.470505Z","end":"2026-07-17T18:09:54.760203Z","steps":["trace[626959812] 'process raft request' (duration: 182.629055ms)","trace[626959812] 'compare' (duration: 106.296265ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:54.760619Z","caller":"traceutil/trace.go:171","msg":"trace[1348313555] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"289.354984ms","start":"2026-07-17T18:09:54.471252Z","end":"2026-07-17T18:09:54.760607Z","steps":["trace[1348313555] 'process raft request' (duration: 288.557439ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:54.760808Z","caller":"traceutil/trace.go:171","msg":"trace[1914840918] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"289.451748ms","start":"2026-07-17T18:09:54.471343Z","end":"2026-07-17T18:09:54.760794Z","steps":["trace[1914840918] 'process raft request' (duration: 288.495037ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:54.760975Z","caller":"traceutil/trace.go:171","msg":"trace[269882175] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1313; }","duration":"248.409066ms","start":"2026-07-17T18:09:54.512552Z","end":"2026-07-17T18:09:54.760961Z","steps":["trace[269882175] 'read index received' (duration: 140.582892ms)","trace[269882175] 'applied index is now lower than readState.Index' (duration: 107.823774ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:54.761035Z","caller":"traceutil/trace.go:171","msg":"trace[1509626283] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"289.506969ms","start":"2026-07-17T18:09:54.47152Z","end":"2026-07-17T18:09:54.761027Z","steps":["trace[1509626283] 'process raft request' (duration: 288.354993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:54.798254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.689588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:54.798335Z","caller":"traceutil/trace.go:171","msg":"trace[1070538083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"285.822312ms","start":"2026-07-17T18:09:54.512492Z","end":"2026-07-17T18:09:54.798314Z","steps":["trace[1070538083] 'agreement among raft nodes before linearized reading' (duration: 248.51458ms)","trace[1070538083] 'range keys from in-memory index tree' (duration: 37.182718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:09:56.994336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122670577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:09:57.465491Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.03631313s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T18:09:57.465934Z","caller":"traceutil/trace.go:171","msg":"trace[64518593] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"1.036828987s","start":"2026-07-17T18:09:56.429082Z","end":"2026-07-17T18:09:57.465911Z","steps":["trace[64518593] 'process raft request' (duration: 1.036645291s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.466082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:56.429059Z","time spent":"1.036949311s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:09:57.650963Z","caller":"traceutil/trace.go:171","msg":"trace[1999205589] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.17933134s","start":"2026-07-17T18:09:56.471617Z","end":"2026-07-17T18:09:57.650948Z","steps":["trace[1999205589] 'process raft request' (duration: 1.169600659s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.651066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:56.471601Z","time spent":"1.179408452s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:09:57.650951Z","caller":"traceutil/trace.go:171","msg":"trace[602418072] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"1.157626816s","start":"2026-07-17T18:09:56.493295Z","end":"2026-07-17T18:09:57.650922Z","steps":["trace[602418072] 'read index received' (duration: 973.295516ms)","trace[602418072] 'applied index is now lower than readState.Index' (duration: 184.33015ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:09:57.651137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157833032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T18:09:57.651178Z","caller":"traceutil/trace.go:171","msg":"trace[116371759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"1.157905685s","start":"2026-07-17T18:09:56.493261Z","end":"2026-07-17T18:09:57.651167Z","steps":["trace[116371759] 'agreement among raft nodes before linearized reading' (duration: 1.157736729s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.651211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:56.493248Z","time spent":"1.157955456s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T18:09:57.651221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137303917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:57.651316Z","caller":"traceutil/trace.go:171","msg":"trace[2119651408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"1.137384989s","start":"2026-07-17T18:09:56.513871Z","end":"2026-07-17T18:09:57.651256Z","steps":["trace[2119651408] 'agreement among raft nodes before linearized reading' (duration: 1.137308117s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.651362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:56.513858Z","time spent":"1.137493562s","remote":"127.0.0.1:53816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T18:09:57.651408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.836284ms","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-07-17T18:09:57.651444Z","caller":"traceutil/trace.go:171","msg":"trace[699176962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1272; }","duration":"886.889096ms","start":"2026-07-17T18:09:56.764546Z","end":"2026-07-17T18:09:57.651435Z","steps":["trace[699176962] 'agreement among raft nodes before linearized reading' (duration: 886.800043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.651448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.670283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T18:09:57.651472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:56.764537Z","time spent":"886.928727ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T18:09:57.651485Z","caller":"traceutil/trace.go:171","msg":"trace[1749066450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1272; }","duration":"883.722014ms","start":"2026-07-17T18:09:56.767754Z","end":"2026-07-17T18:09:57.651476Z","steps":["trace[1749066450] 'agreement among raft nodes before linearized reading' (duration: 883.58465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.651512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:56.767747Z","time spent":"883.758346ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T18:09:57.651577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.426451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-17T18:09:57.651597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.011822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T18:09:57.651605Z","caller":"traceutil/trace.go:171","msg":"trace[1444651068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"886.469312ms","start":"2026-07-17T18:09:56.765126Z","end":"2026-07-17T18:09:57.651595Z","steps":["trace[1444651068] 'agreement among raft nodes before linearized reading' (duration: 886.40119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.651615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.947033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T18:09:57.651626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:56.765116Z","time spent":"886.506084ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T18:09:57.651645Z","caller":"traceutil/trace.go:171","msg":"trace[1779581434] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1272; }","duration":"377.021716ms","start":"2026-07-17T18:09:57.274614Z","end":"2026-07-17T18:09:57.651635Z","steps":["trace[1779581434] 'agreement among raft nodes before linearized reading' (duration: 376.968684ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:57.651627Z","caller":"traceutil/trace.go:171","msg":"trace[2099159709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"888.042183ms","start":"2026-07-17T18:09:56.763575Z","end":"2026-07-17T18:09:57.651618Z","steps":["trace[2099159709] 'agreement among raft nodes before linearized reading' (duration: 887.96174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.651675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:57.274598Z","time spent":"377.066897ms","remote":"127.0.0.1:40328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:09:57.651682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:56.763521Z","time spent":"888.156247ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T18:09:57.871135Z","caller":"traceutil/trace.go:171","msg":"trace[88108154] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"215.625298ms","start":"2026-07-17T18:09:57.655479Z","end":"2026-07-17T18:09:57.871104Z","steps":["trace[88108154] 'process raft request' (duration: 215.36909ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:57.871598Z","caller":"traceutil/trace.go:171","msg":"trace[101604095] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"215.427292ms","start":"2026-07-17T18:09:57.65611Z","end":"2026-07-17T18:09:57.871538Z","steps":["trace[101604095] 'process raft request' (duration: 214.911566ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:57.87165Z","caller":"traceutil/trace.go:171","msg":"trace[455729510] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"215.398471ms","start":"2026-07-17T18:09:57.656216Z","end":"2026-07-17T18:09:57.871615Z","steps":["trace[455729510] 'process raft request' (duration: 215.215585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:57.8718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.285168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:09:57.871794Z","caller":"traceutil/trace.go:171","msg":"trace[894906507] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"215.38375ms","start":"2026-07-17T18:09:57.656374Z","end":"2026-07-17T18:09:57.871758Z","steps":["trace[894906507] 'process raft request' (duration: 215.178654ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:57.871842Z","caller":"traceutil/trace.go:171","msg":"trace[1010926331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"210.35569ms","start":"2026-07-17T18:09:57.661478Z","end":"2026-07-17T18:09:57.871833Z","steps":["trace[1010926331] 'agreement among raft nodes before linearized reading' (duration: 210.258387ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:09:57.871699Z","caller":"traceutil/trace.go:171","msg":"trace[1488564353] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1321; }","duration":"210.182824ms","start":"2026-07-17T18:09:57.661497Z","end":"2026-07-17T18:09:57.87168Z","steps":["trace[1488564353] 'read index received' (duration: 43.794409ms)","trace[1488564353] 'applied index is now lower than readState.Index' (duration: 166.386755ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:59.610483Z","caller":"traceutil/trace.go:171","msg":"trace[1229496617] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"239.098679ms","start":"2026-07-17T18:09:59.371358Z","end":"2026-07-17T18:09:59.610457Z","steps":["trace[1229496617] 'process raft request' (duration: 151.346735ms)","trace[1229496617] 'compare' (duration: 87.606409ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:09:59.985156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.765988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:09:59.985246Z","caller":"traceutil/trace.go:171","msg":"trace[1876159649] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"277.226987ms","start":"2026-07-17T18:09:59.708005Z","end":"2026-07-17T18:09:59.985232Z","steps":["trace[1876159649] 'read index received' (duration: 95.248553ms)","trace[1876159649] 'applied index is now lower than readState.Index' (duration: 181.977194ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:09:59.985307Z","caller":"traceutil/trace.go:171","msg":"trace[932170267] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"315.598333ms","start":"2026-07-17T18:09:59.669698Z","end":"2026-07-17T18:09:59.985296Z","steps":["trace[932170267] 'process raft request' (duration: 133.616759ms)","trace[932170267] 'compare' (duration: 181.586221ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:09:59.985338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:59.669679Z","time spent":"315.641705ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:09:59.985451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.511789ms","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-07-17T18:09:59.985515Z","caller":"traceutil/trace.go:171","msg":"trace[1292358582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"109.613142ms","start":"2026-07-17T18:09:59.875885Z","end":"2026-07-17T18:09:59.985498Z","steps":["trace[1292358582] 'agreement among raft nodes before linearized reading' (duration: 109.424966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:59.985611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.573069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T18:09:59.985635Z","caller":"traceutil/trace.go:171","msg":"trace[70143530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"108.613059ms","start":"2026-07-17T18:09:59.877015Z","end":"2026-07-17T18:09:59.985628Z","steps":["trace[70143530] 'agreement among raft nodes before linearized reading' (duration: 108.511146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:59.985712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.707722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T18:09:59.985728Z","caller":"traceutil/trace.go:171","msg":"trace[1993382106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"277.750983ms","start":"2026-07-17T18:09:59.707972Z","end":"2026-07-17T18:09:59.985723Z","steps":["trace[1993382106] 'agreement among raft nodes before linearized reading' (duration: 277.708042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:59.98581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.682044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T18:09:59.985858Z","caller":"traceutil/trace.go:171","msg":"trace[1283330566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1279; }","duration":"109.740515ms","start":"2026-07-17T18:09:59.876108Z","end":"2026-07-17T18:09:59.985848Z","steps":["trace[1283330566] 'agreement among raft nodes before linearized reading' (duration: 109.631182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:09:59.985924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.954303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T18:09:59.98594Z","caller":"traceutil/trace.go:171","msg":"trace[1025446078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"109.980663ms","start":"2026-07-17T18:09:59.875955Z","end":"2026-07-17T18:09:59.985936Z","steps":["trace[1025446078] 'agreement among raft nodes before linearized reading' (duration: 109.933472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:00.560766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122670601,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:10:01.061663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122670601,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:10:01.070953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.08140081s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T18:10:01.787987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.186772ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:10:01.788096Z","caller":"traceutil/trace.go:171","msg":"trace[650576967] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1279; }","duration":"434.301975ms","start":"2026-07-17T18:10:01.353776Z","end":"2026-07-17T18:10:01.788078Z","steps":["trace[650576967] 'range keys from in-memory index tree' (duration: 434.173931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:01.788278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.079749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:10:01.78836Z","caller":"traceutil/trace.go:171","msg":"trace[1897115286] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"1.798892873s","start":"2026-07-17T18:09:59.98945Z","end":"2026-07-17T18:10:01.788342Z","steps":["trace[1897115286] 'process raft request' (duration: 1.081680309s)","trace[1897115286] 'compare' (duration: 716.951805ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:10:01.788414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:59.989424Z","time spent":"1.798963285s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:02.513489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000342189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-17T18:10:02.513579Z","caller":"traceutil/trace.go:171","msg":"trace[597298622] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000511615s","start":"2026-07-17T18:10:00.513041Z","end":"2026-07-17T18:10:02.513553Z","steps":["trace[597298622] 'agreement among raft nodes before linearized reading' (duration: 2.000395081s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:02.513627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:00.513018Z","time spent":"2.000598818s","remote":"127.0.0.1:53816","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-07-17T18:10:03.2181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.146992914s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T18:10:03.218518Z","caller":"traceutil/trace.go:171","msg":"trace[127375140] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"3.228986764s","start":"2026-07-17T18:09:59.989502Z","end":"2026-07-17T18:10:03.218489Z","steps":["trace[127375140] 'process raft request' (duration: 3.228793648s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.218639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.158347747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T18:10:03.218629Z","caller":"traceutil/trace.go:171","msg":"trace[1059030533] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"3.228675104s","start":"2026-07-17T18:09:59.98988Z","end":"2026-07-17T18:10:03.218555Z","steps":["trace[1059030533] 'process raft request' (duration: 3.228572411s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:03.218658Z","caller":"traceutil/trace.go:171","msg":"trace[1331471097] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"3.227953321s","start":"2026-07-17T18:09:59.990653Z","end":"2026-07-17T18:10:03.218606Z","steps":["trace[1331471097] 'process raft request' (duration: 3.227821567s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:03.218678Z","caller":"traceutil/trace.go:171","msg":"trace[2128706251] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"3.229070026s","start":"2026-07-17T18:09:59.989585Z","end":"2026-07-17T18:10:03.218655Z","steps":["trace[2128706251] 'process raft request' (duration: 3.228834389s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.218644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:59.989488Z","time spent":"3.229091987s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:03.218525Z","caller":"traceutil/trace.go:171","msg":"trace[1035429920] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1328; }","duration":"3.158236824s","start":"2026-07-17T18:10:00.060269Z","end":"2026-07-17T18:10:03.218506Z","steps":["trace[1035429920] 'read index received' (duration: 1.010843166s)","trace[1035429920] 'applied index is now lower than readState.Index' (duration: 2.147392857s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:10:03.218893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:59.989581Z","time spent":"3.229213901s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:03.218678Z","caller":"traceutil/trace.go:171","msg":"trace[1440807683] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1284; }","duration":"3.15844237s","start":"2026-07-17T18:10:00.060228Z","end":"2026-07-17T18:10:03.21867Z","steps":["trace[1440807683] 'agreement among raft nodes before linearized reading' (duration: 3.158338916s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.218909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:59.989858Z","time spent":"3.22889181s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:03.218962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:00.060213Z","time spent":"3.158738519s","remote":"127.0.0.1:53966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:10:03.219143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:09:59.990641Z","time spent":"3.228112495s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:03.248502Z","caller":"traceutil/trace.go:171","msg":"trace[1908067044] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"1.258568421s","start":"2026-07-17T18:10:01.989916Z","end":"2026-07-17T18:10:03.248484Z","steps":["trace[1908067044] 'process raft request' (duration: 1.258433486s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.248637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:01.989893Z","time spent":"1.258662774s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:03.24866Z","caller":"traceutil/trace.go:171","msg":"trace[479665152] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"1.247682313s","start":"2026-07-17T18:10:02.00096Z","end":"2026-07-17T18:10:03.248642Z","steps":["trace[479665152] 'process raft request' (duration: 1.247492747s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.248732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665363008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T18:10:03.248767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:02.000928Z","time spent":"1.247778896s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:03.248841Z","caller":"traceutil/trace.go:171","msg":"trace[2087633944] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1286; }","duration":"1.665512513s","start":"2026-07-17T18:10:01.583262Z","end":"2026-07-17T18:10:03.248775Z","steps":["trace[2087633944] 'agreement among raft nodes before linearized reading' (duration: 1.665383769s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.249009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.308317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:10:03.249042Z","caller":"traceutil/trace.go:171","msg":"trace[1011239589] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1286; }","duration":"501.386269ms","start":"2026-07-17T18:10:02.747647Z","end":"2026-07-17T18:10:03.249033Z","steps":["trace[1011239589] 'agreement among raft nodes before linearized reading' (duration: 501.334518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.249064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:02.747624Z","time spent":"501.434411ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:10:03.249036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:01.583248Z","time spent":"1.665697598s","remote":"127.0.0.1:40432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:10:03.249188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.903109ms","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-07-17T18:10:03.249213Z","caller":"traceutil/trace.go:171","msg":"trace[653130636] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1286; }","duration":"431.957241ms","start":"2026-07-17T18:10:02.817249Z","end":"2026-07-17T18:10:03.249206Z","steps":["trace[653130636] 'agreement among raft nodes before linearized reading' (duration: 431.913779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.249232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:02.817233Z","time spent":"431.994672ms","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:10:03.249213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.119577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:10:03.249388Z","caller":"traceutil/trace.go:171","msg":"trace[10794547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"735.322583ms","start":"2026-07-17T18:10:02.514052Z","end":"2026-07-17T18:10:03.249374Z","steps":["trace[10794547] 'agreement among raft nodes before linearized reading' (duration: 734.995293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:03.249429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:02.514034Z","time spent":"735.385386ms","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T18:10:04.146673Z","caller":"traceutil/trace.go:171","msg":"trace[1263773339] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"350.838328ms","start":"2026-07-17T18:10:03.795811Z","end":"2026-07-17T18:10:04.14665Z","steps":["trace[1263773339] 'process raft request' (duration: 350.675233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:04.146821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:03.79579Z","time spent":"350.953212ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:05.008159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.27805ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T18:10:05.338888Z","caller":"traceutil/trace.go:171","msg":"trace[964621989] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"111.945457ms","start":"2026-07-17T18:10:05.226919Z","end":"2026-07-17T18:10:05.338864Z","steps":["trace[964621989] 'process raft request' (duration: 111.788252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:06.446308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.367832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:10:06.446587Z","caller":"traceutil/trace.go:171","msg":"trace[264441239] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1339; }","duration":"932.40934ms","start":"2026-07-17T18:10:05.514158Z","end":"2026-07-17T18:10:06.446567Z","steps":["trace[264441239] 'read index received' (duration: 259.589014ms)","trace[264441239] 'applied index is now lower than readState.Index' (duration: 672.819436ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:10:06.446843Z","caller":"traceutil/trace.go:171","msg":"trace[1955508439] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"1.21939179s","start":"2026-07-17T18:10:05.227436Z","end":"2026-07-17T18:10:06.446828Z","steps":["trace[1955508439] 'process raft request' (duration: 546.406768ms)","trace[1955508439] 'compare' (duration: 671.99648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:10:06.446908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:05.227417Z","time spent":"1.219455771s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:06.447072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.902576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T18:10:06.447117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.379017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T18:10:06.447141Z","caller":"traceutil/trace.go:171","msg":"trace[361371821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"933.010249ms","start":"2026-07-17T18:10:05.514112Z","end":"2026-07-17T18:10:06.447122Z","steps":["trace[361371821] 'agreement among raft nodes before linearized reading' (duration: 932.890735ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:06.447158Z","caller":"traceutil/trace.go:171","msg":"trace[1464041523] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"1.219521864s","start":"2026-07-17T18:10:05.22762Z","end":"2026-07-17T18:10:06.447142Z","steps":["trace[1464041523] 'process raft request' (duration: 1.218874803s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:06.447184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:05.514095Z","time spent":"933.076932ms","remote":"127.0.0.1:53816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T18:10:06.447185Z","caller":"traceutil/trace.go:171","msg":"trace[1206704964] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1293; }","duration":"577.4905ms","start":"2026-07-17T18:10:05.86967Z","end":"2026-07-17T18:10:06.44716Z","steps":["trace[1206704964] 'agreement among raft nodes before linearized reading' (duration: 577.389547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:06.447236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:05.869646Z","time spent":"577.576093ms","remote":"127.0.0.1:54330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-17T18:10:06.447242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:05.227603Z","time spent":"1.219597006s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:06.447387Z","caller":"traceutil/trace.go:171","msg":"trace[2121216244] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"1.219808943s","start":"2026-07-17T18:10:05.22756Z","end":"2026-07-17T18:10:06.447369Z","steps":["trace[2121216244] 'process raft request' (duration: 1.218869393s)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:06.447429Z","caller":"traceutil/trace.go:171","msg":"trace[573397133] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"1.190879008s","start":"2026-07-17T18:10:05.256536Z","end":"2026-07-17T18:10:06.447415Z","steps":["trace[573397133] 'process raft request' (duration: 1.18999081s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:06.447443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:05.227524Z","time spent":"1.219889645s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:06.447534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:05.256515Z","time spent":"1.190941871s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:06.447255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.76783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T18:10:06.448281Z","caller":"traceutil/trace.go:171","msg":"trace[195923633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"296.829824ms","start":"2026-07-17T18:10:06.151432Z","end":"2026-07-17T18:10:06.448262Z","steps":["trace[195923633] 'agreement among raft nodes before linearized reading' (duration: 295.624965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:07.014974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122670632,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T18:10:07.510725Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.03821129s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T18:10:07.511054Z","caller":"traceutil/trace.go:171","msg":"trace[1023897758] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"996.746735ms","start":"2026-07-17T18:10:06.51429Z","end":"2026-07-17T18:10:07.511036Z","steps":["trace[1023897758] 'read index received' (duration: 996.637562ms)","trace[1023897758] 'applied index is now lower than readState.Index' (duration: 108.073µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:10:07.511133Z","caller":"traceutil/trace.go:171","msg":"trace[1770619042] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"1.058035643s","start":"2026-07-17T18:10:06.45309Z","end":"2026-07-17T18:10:07.511126Z","steps":["trace[1770619042] 'process raft request' (duration: 1.057845447s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:07.511209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:06.453074Z","time spent":"1.058076365s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:07.511308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.665494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:10:07.51134Z","caller":"traceutil/trace.go:171","msg":"trace[393440538] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1294; }","duration":"104.968214ms","start":"2026-07-17T18:10:07.406363Z","end":"2026-07-17T18:10:07.511331Z","steps":["trace[393440538] 'agreement among raft nodes before linearized reading' (duration: 104.894921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:07.511334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.032394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T18:10:07.511334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.641577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-17T18:10:07.511382Z","caller":"traceutil/trace.go:171","msg":"trace[1597264243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"997.123638ms","start":"2026-07-17T18:10:06.514243Z","end":"2026-07-17T18:10:07.511366Z","steps":["trace[1597264243] 'agreement among raft nodes before linearized reading' (duration: 997.019725ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:07.511389Z","caller":"traceutil/trace.go:171","msg":"trace[2078606917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1294; }","duration":"168.72736ms","start":"2026-07-17T18:10:07.342646Z","end":"2026-07-17T18:10:07.511374Z","steps":["trace[2078606917] 'agreement among raft nodes before linearized reading' (duration: 168.516683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:07.511417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:06.514218Z","time spent":"997.190179ms","remote":"127.0.0.1:53816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T18:10:08.167058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.998511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T18:10:08.167207Z","caller":"traceutil/trace.go:171","msg":"trace[182934142] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"543.920998ms","start":"2026-07-17T18:10:07.623257Z","end":"2026-07-17T18:10:08.167178Z","steps":["trace[182934142] 'process raft request' (duration: 435.606257ms)","trace[182934142] 'compare' (duration: 107.616959ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:10:08.167371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:07.623233Z","time spent":"544.024812ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:09.013681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514900122670643,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T18:10:09.149431Z","caller":"traceutil/trace.go:171","msg":"trace[2067251450] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"694.483688ms","start":"2026-07-17T18:10:08.454923Z","end":"2026-07-17T18:10:09.149407Z","steps":["trace[2067251450] 'process raft request' (duration: 694.332963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:09.149579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:08.45491Z","time spent":"694.592601ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:09.580077Z","caller":"traceutil/trace.go:171","msg":"trace[846368331] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"1.124795076s","start":"2026-07-17T18:10:08.455238Z","end":"2026-07-17T18:10:09.580033Z","steps":["trace[846368331] 'process raft request' (duration: 1.124447565s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:09.58026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:08.455209Z","time spent":"1.12493368s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:09.580451Z","caller":"traceutil/trace.go:171","msg":"trace[310802160] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1346; }","duration":"1.067496406s","start":"2026-07-17T18:10:08.512933Z","end":"2026-07-17T18:10:09.58043Z","steps":["trace[310802160] 'read index received' (duration: 636.30912ms)","trace[310802160] 'applied index is now lower than readState.Index' (duration: 431.186346ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:10:09.580605Z","caller":"traceutil/trace.go:171","msg":"trace[539571280] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.125006523s","start":"2026-07-17T18:10:08.455578Z","end":"2026-07-17T18:10:09.580585Z","steps":["trace[539571280] 'process raft request' (duration: 1.12430126s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:09.580644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067705953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:10:09.580622Z","caller":"traceutil/trace.go:171","msg":"trace[1291162336] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"1.122860634s","start":"2026-07-17T18:10:08.457717Z","end":"2026-07-17T18:10:09.580578Z","steps":["trace[1291162336] 'process raft request' (duration: 1.122654508s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:09.580684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:08.455572Z","time spent":"1.125075125s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:09.580701Z","caller":"traceutil/trace.go:171","msg":"trace[1070915627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"1.067813006s","start":"2026-07-17T18:10:08.512872Z","end":"2026-07-17T18:10:09.580685Z","steps":["trace[1070915627] 'agreement among raft nodes before linearized reading' (duration: 1.067698882s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:09.580697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.52729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:10:09.580756Z","caller":"traceutil/trace.go:171","msg":"trace[290029916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"689.630124ms","start":"2026-07-17T18:10:08.891109Z","end":"2026-07-17T18:10:09.580739Z","steps":["trace[290029916] 'agreement among raft nodes before linearized reading' (duration: 689.52369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:09.580745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:08.51285Z","time spent":"1.067883578s","remote":"127.0.0.1:53804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T18:10:09.580797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:08.891094Z","time spent":"689.693095ms","remote":"127.0.0.1:53816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T18:10:09.580794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:08.457703Z","time spent":"1.123006129s","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T18:10:09.580898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.691856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-17T18:10:09.580922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.232845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T18:10:09.580959Z","caller":"traceutil/trace.go:171","msg":"trace[1418568954] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1300; }","duration":"450.313797ms","start":"2026-07-17T18:10:09.130635Z","end":"2026-07-17T18:10:09.580949Z","steps":["trace[1418568954] 'agreement among raft nodes before linearized reading' (duration: 450.251835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:09.580993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:09.130611Z","time spent":"450.374919ms","remote":"127.0.0.1:54196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-07-17T18:10:09.580958Z","caller":"traceutil/trace.go:171","msg":"trace[321872193] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1300; }","duration":"264.793ms","start":"2026-07-17T18:10:09.316146Z","end":"2026-07-17T18:10:09.580939Z","steps":["trace[321872193] 'agreement among raft nodes before linearized reading' (duration: 264.614664ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:10.152036Z","caller":"traceutil/trace.go:171","msg":"trace[1978637340] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"539.059253ms","start":"2026-07-17T18:10:09.61294Z","end":"2026-07-17T18:10:10.151999Z","steps":["trace[1978637340] 'process raft request' (duration: 537.390919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:10.152181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:09.612924Z","time spent":"539.181277ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T18:10:17.500822Z","caller":"traceutil/trace.go:171","msg":"trace[1358550658] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"291.936168ms","start":"2026-07-17T18:10:17.208827Z","end":"2026-07-17T18:10:17.500764Z","steps":["trace[1358550658] 'process raft request' (duration: 291.781343ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:19.083939Z","caller":"traceutil/trace.go:171","msg":"trace[959035040] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"325.155509ms","start":"2026-07-17T18:10:18.758761Z","end":"2026-07-17T18:10:19.083916Z","steps":["trace[959035040] 'read index received' (duration: 324.985093ms)","trace[959035040] 'applied index is now lower than readState.Index' (duration: 169.616µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:10:19.084169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.383976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-17T18:10:19.084236Z","caller":"traceutil/trace.go:171","msg":"trace[1757621190] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1359; }","duration":"325.471919ms","start":"2026-07-17T18:10:18.758727Z","end":"2026-07-17T18:10:19.084198Z","steps":["trace[1757621190] 'agreement among raft nodes before linearized reading' (duration: 325.307053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:19.084289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:18.758704Z","time spent":"325.567111ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T18:10:19.084279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.14269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T18:10:19.084421Z","caller":"traceutil/trace.go:171","msg":"trace[813562659] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1359; }","duration":"254.333815ms","start":"2026-07-17T18:10:18.830039Z","end":"2026-07-17T18:10:19.084373Z","steps":["trace[813562659] 'agreement among raft nodes before linearized reading' (duration: 254.089227ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:19.084176Z","caller":"traceutil/trace.go:171","msg":"trace[1069601907] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"326.911074ms","start":"2026-07-17T18:10:18.757055Z","end":"2026-07-17T18:10:19.083966Z","steps":["trace[1069601907] 'process raft request' (duration: 326.737289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:19.084809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T18:10:18.757035Z","time spent":"327.69663ms","remote":"127.0.0.1:53898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T18:10:19.265224Z","caller":"traceutil/trace.go:171","msg":"trace[391855075] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"175.583238ms","start":"2026-07-17T18:10:19.089609Z","end":"2026-07-17T18:10:19.265193Z","steps":["trace[391855075] 'process raft request' (duration: 168.638867ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:19.265763Z","caller":"traceutil/trace.go:171","msg":"trace[466527059] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"176.120536ms","start":"2026-07-17T18:10:19.089617Z","end":"2026-07-17T18:10:19.265738Z","steps":["trace[466527059] 'process raft request' (duration: 175.751164ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:19.265914Z","caller":"traceutil/trace.go:171","msg":"trace[1137925623] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"175.30382ms","start":"2026-07-17T18:10:19.090421Z","end":"2026-07-17T18:10:19.265725Z","steps":["trace[1137925623] 'process raft request' (duration: 175.099414ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:25.00219Z","caller":"traceutil/trace.go:171","msg":"trace[105160178] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1460; }","duration":"207.765628ms","start":"2026-07-17T18:10:24.794395Z","end":"2026-07-17T18:10:25.00216Z","steps":["trace[105160178] 'read index received' (duration: 205.662921ms)","trace[105160178] 'applied index is now lower than readState.Index' (duration: 2.100227ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:10:25.002586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.176061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-17T18:10:25.002652Z","caller":"traceutil/trace.go:171","msg":"trace[580318354] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1407; }","duration":"208.288754ms","start":"2026-07-17T18:10:24.794341Z","end":"2026-07-17T18:10:25.00263Z","steps":["trace[580318354] 'agreement among raft nodes before linearized reading' (duration: 207.972424ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:27.12314Z","caller":"traceutil/trace.go:171","msg":"trace[1972518529] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"104.375135ms","start":"2026-07-17T18:10:27.018745Z","end":"2026-07-17T18:10:27.12312Z","steps":["trace[1972518529] 'process raft request' (duration: 104.22251ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:27.643889Z","caller":"traceutil/trace.go:171","msg":"trace[101894318] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"112.464313ms","start":"2026-07-17T18:10:27.531398Z","end":"2026-07-17T18:10:27.643863Z","steps":["trace[101894318] 'process raft request' (duration: 112.35311ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:33.848381Z","caller":"traceutil/trace.go:171","msg":"trace[220791887] linearizableReadLoop","detail":"{readStateIndex:1502; appliedIndex:1501; }","duration":"154.114921ms","start":"2026-07-17T18:10:33.694238Z","end":"2026-07-17T18:10:33.848353Z","steps":["trace[220791887] 'read index received' (duration: 153.841812ms)","trace[220791887] 'applied index is now lower than readState.Index' (duration: 268.799µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T18:10:33.848536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.274136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T18:10:33.848701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.428087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T18:10:33.848732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.102556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T18:10:33.848757Z","caller":"traceutil/trace.go:171","msg":"trace[1099797246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1447; }","duration":"138.51338ms","start":"2026-07-17T18:10:33.710231Z","end":"2026-07-17T18:10:33.848745Z","steps":["trace[1099797246] 'agreement among raft nodes before linearized reading' (duration: 138.328895ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:33.848796Z","caller":"traceutil/trace.go:171","msg":"trace[118201627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1447; }","duration":"142.200189ms","start":"2026-07-17T18:10:33.706581Z","end":"2026-07-17T18:10:33.848781Z","steps":["trace[118201627] 'agreement among raft nodes before linearized reading' (duration: 142.079835ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:33.848691Z","caller":"traceutil/trace.go:171","msg":"trace[1841015695] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1447; }","duration":"154.473842ms","start":"2026-07-17T18:10:33.694201Z","end":"2026-07-17T18:10:33.848675Z","steps":["trace[1841015695] 'agreement among raft nodes before linearized reading' (duration: 154.274746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:33.848632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.24055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T18:10:33.848881Z","caller":"traceutil/trace.go:171","msg":"trace[709395935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1447; }","duration":"147.5195ms","start":"2026-07-17T18:10:33.701353Z","end":"2026-07-17T18:10:33.848872Z","steps":["trace[709395935] 'agreement among raft nodes before linearized reading' (duration: 147.157208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:10:33.84888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.323676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T18:10:33.84898Z","caller":"traceutil/trace.go:171","msg":"trace[389300207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1447; }","duration":"139.468091ms","start":"2026-07-17T18:10:33.709491Z","end":"2026-07-17T18:10:33.848959Z","steps":["trace[389300207] 'agreement among raft nodes before linearized reading' (duration: 139.141791ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:33.848432Z","caller":"traceutil/trace.go:171","msg":"trace[1752125978] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"183.267026ms","start":"2026-07-17T18:10:33.665145Z","end":"2026-07-17T18:10:33.848412Z","steps":["trace[1752125978] 'process raft request' (duration: 183.020908ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:39.447173Z","caller":"traceutil/trace.go:171","msg":"trace[1470705331] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"100.75649ms","start":"2026-07-17T18:10:39.346393Z","end":"2026-07-17T18:10:39.447149Z","steps":["trace[1470705331] 'process raft request' (duration: 100.654587ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:10:39.44739Z","caller":"traceutil/trace.go:171","msg":"trace[2021308106] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"119.911084ms","start":"2026-07-17T18:10:39.327454Z","end":"2026-07-17T18:10:39.447365Z","steps":["trace[2021308106] 'process raft request' (duration: 56.928035ms)","trace[2021308106] 'compare' (duration: 62.485653ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T18:11:25.581165Z","caller":"traceutil/trace.go:171","msg":"trace[1213483381] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"122.657482ms","start":"2026-07-17T18:11:25.458481Z","end":"2026-07-17T18:11:25.581138Z","steps":["trace[1213483381] 'process raft request' (duration: 122.505387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:14:29.289946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.861682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-17T18:14:29.290097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.89404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-17T18:14:29.290076Z","caller":"traceutil/trace.go:171","msg":"trace[1390421590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2552; }","duration":"130.030117ms","start":"2026-07-17T18:14:29.160023Z","end":"2026-07-17T18:14:29.290053Z","steps":["trace[1390421590] 'range keys from in-memory index tree' (duration: 129.734478ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T18:14:29.290192Z","caller":"traceutil/trace.go:171","msg":"trace[1104678234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2552; }","duration":"139.009373ms","start":"2026-07-17T18:14:29.151163Z","end":"2026-07-17T18:14:29.290172Z","steps":["trace[1104678234] 'range keys from in-memory index tree' (duration: 138.705464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T18:14:29.290815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.269064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T18:14:29.290883Z","caller":"traceutil/trace.go:171","msg":"trace[536952392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2552; }","duration":"135.351497ms","start":"2026-07-17T18:14:29.155515Z","end":"2026-07-17T18:14:29.290866Z","steps":["trace[536952392] 'range keys from in-memory index tree' (duration: 134.20344ms)"],"step_count":1}