{"level":"warn","ts":"2026-07-17T10:56:56.915365Z","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-17T10:56:56.915445Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-17T10:56:56.915507Z","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-17T10:56:56.915518Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-17T10:56:56.915533Z","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-17T10:56:56.916246Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-07-17T10:56:56.916317Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-17T10:56:56.920272Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.757796ms"} {"level":"info","ts":"2026-07-17T10:56:56.926196Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-07-17T10:56:56.92626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T10:56:56.926283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-07-17T10:56:56.926395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T10:56:56.92644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-07-17T10:56:56.926604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-07-17T10:56:56.931881Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T10:56:56.933374Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T10:56:56.934665Z","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-17T10:56:56.937521Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T10:56:56.937858Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T10:56:56.937851Z","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-17T10:56:56.938183Z","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-17T10:56:56.93851Z","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-17T10:56:56.938532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-07-17T10:56:56.939959Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-17T10:56:56.942496Z","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-17T10:56:56.942657Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-17T10:56:56.942944Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-17T10:56:56.943444Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T10:56:56.943504Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T10:56:57.727513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T10:56:57.727576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T10:56:57.727635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-07-17T10:56:57.727664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-07-17T10:56:57.727681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-17T10:56:57.727703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-07-17T10:56:57.727723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-17T10:56:57.728869Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T10:56:57.728914Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T10:56:57.728915Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T10:56:57.728984Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:56:57.729374Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T10:56:57.729424Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T10:56:57.730096Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:56:57.730192Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:56:57.73022Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T10:56:57.731543Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T10:56:57.732938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"warn","ts":"2026-07-17T10:57:01.525913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.245301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:01.52603Z","caller":"traceutil/trace.go:171","msg":"trace[417395699] linearizableReadLoop","detail":"{readStateIndex:141; appliedIndex:140; }","duration":"483.207889ms","start":"2026-07-17T10:57:01.042805Z","end":"2026-07-17T10:57:01.526013Z","steps":["trace[417395699] 'read index received' (duration: 67.781µs)","trace[417395699] 'applied index is now lower than readState.Index' (duration: 483.138988ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:01.526098Z","caller":"traceutil/trace.go:171","msg":"trace[96272784] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"1.190506968s","start":"2026-07-17T10:57:00.335568Z","end":"2026-07-17T10:57:01.526075Z","steps":["trace[96272784] 'process raft request' (duration: 342.652016ms)","trace[96272784] 'compare' (duration: 847.087148ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:01.526209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.514607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-17T10:57:01.526186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:00.335545Z","time spent":"1.190602519s","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":678,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:01.526247Z","caller":"traceutil/trace.go:171","msg":"trace[1872587577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:137; }","duration":"437.579918ms","start":"2026-07-17T10:57:01.088658Z","end":"2026-07-17T10:57:01.526238Z","steps":["trace[1872587577] 'agreement among raft nodes before linearized reading' (duration: 437.472666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:01.52628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:01.088648Z","time spent":"437.622359ms","remote":"127.0.0.1:39320","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-17T10:57:01.526275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.431172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T10:57:01.526328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.701603ms","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-17T10:57:01.526376Z","caller":"traceutil/trace.go:171","msg":"trace[517725722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:137; }","duration":"483.595656ms","start":"2026-07-17T10:57:01.042764Z","end":"2026-07-17T10:57:01.52636Z","steps":["trace[517725722] 'agreement among raft nodes before linearized reading' (duration: 483.350301ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:01.526422Z","caller":"traceutil/trace.go:171","msg":"trace[1938650474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:137; }","duration":"436.867116ms","start":"2026-07-17T10:57:01.089539Z","end":"2026-07-17T10:57:01.526406Z","steps":["trace[1938650474] 'agreement among raft nodes before linearized reading' (duration: 436.643932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:01.526441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:01.04274Z","time spent":"483.693177ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:57:01.526454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:01.089532Z","time spent":"436.916986ms","remote":"127.0.0.1:39320","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-17T10:57:02.552132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253036384,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:02.95274Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.423172659s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T10:57:03.531713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.509559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:03.531914Z","caller":"traceutil/trace.go:171","msg":"trace[1488969511] linearizableReadLoop","detail":"{readStateIndex:144; appliedIndex:141; }","duration":"1.480168023s","start":"2026-07-17T10:57:02.051732Z","end":"2026-07-17T10:57:03.5319Z","steps":["trace[1488969511] 'read index received' (duration: 901.309438ms)","trace[1488969511] 'applied index is now lower than readState.Index' (duration: 578.857765ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:03.531999Z","caller":"traceutil/trace.go:171","msg":"trace[1573734761] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"2.002497313s","start":"2026-07-17T10:57:01.529492Z","end":"2026-07-17T10:57:03.53199Z","steps":["trace[1573734761] 'process raft request' (duration: 1.423458634s)","trace[1573734761] 'compare' (duration: 577.929028ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:03.532048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:01.529479Z","time spent":"2.002546024s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:03.532166Z","caller":"traceutil/trace.go:171","msg":"trace[588587623] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"2.00125731s","start":"2026-07-17T10:57:01.530862Z","end":"2026-07-17T10:57:03.532119Z","steps":["trace[588587623] 'process raft request' (duration: 2.001006046s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:03.532266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.095726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T10:57:03.532314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:01.530837Z","time spent":"2.001417933s","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:57:03.532414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480680603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:03.53246Z","caller":"traceutil/trace.go:171","msg":"trace[736052051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:140; }","duration":"1.480750384s","start":"2026-07-17T10:57:02.051699Z","end":"2026-07-17T10:57:03.532449Z","steps":["trace[736052051] 'agreement among raft nodes before linearized reading' (duration: 1.480684963s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:03.532503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:02.051684Z","time spent":"1.480809545s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T10:57:03.532355Z","caller":"traceutil/trace.go:171","msg":"trace[1909838414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:140; }","duration":"990.220769ms","start":"2026-07-17T10:57:02.542105Z","end":"2026-07-17T10:57:03.532326Z","steps":["trace[1909838414] 'agreement among raft nodes before linearized reading' (duration: 990.022515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:03.532799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:02.542086Z","time spent":"990.700767ms","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T10:57:03.532748Z","caller":"traceutil/trace.go:171","msg":"trace[89518411] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"2.002702867s","start":"2026-07-17T10:57:01.530028Z","end":"2026-07-17T10:57:03.532731Z","steps":["trace[89518411] 'process raft request' (duration: 2.001775721s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:03.532979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:01.530006Z","time spent":"2.002926502s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:03.713057Z","caller":"traceutil/trace.go:171","msg":"trace[817181779] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"174.922316ms","start":"2026-07-17T10:57:03.538109Z","end":"2026-07-17T10:57:03.713032Z","steps":["trace[817181779] 'process raft request' (duration: 161.626047ms)","trace[817181779] 'compare' (duration: 13.037025ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:03.713105Z","caller":"traceutil/trace.go:171","msg":"trace[1512811065] linearizableReadLoop","detail":"{readStateIndex:145; appliedIndex:144; }","duration":"171.85809ms","start":"2026-07-17T10:57:03.541195Z","end":"2026-07-17T10:57:03.713053Z","steps":["trace[1512811065] 'read index received' (duration: 158.380537ms)","trace[1512811065] 'applied index is now lower than readState.Index' (duration: 13.475673ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:03.71342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.201336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:03.713483Z","caller":"traceutil/trace.go:171","msg":"trace[1364446672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:142; }","duration":"172.313198ms","start":"2026-07-17T10:57:03.541155Z","end":"2026-07-17T10:57:03.713468Z","steps":["trace[1364446672] 'agreement among raft nodes before linearized reading' (duration: 172.027692ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:37.057449Z","caller":"traceutil/trace.go:171","msg":"trace[793616017] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"385.776109ms","start":"2026-07-17T10:57:36.671641Z","end":"2026-07-17T10:57:37.057417Z","steps":["trace[793616017] 'process raft request' (duration: 385.422311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:37.057713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:36.67163Z","time spent":"386.023975ms","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:37.058723Z","caller":"traceutil/trace.go:171","msg":"trace[22116391] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:468; }","duration":"317.504825ms","start":"2026-07-17T10:57:36.741162Z","end":"2026-07-17T10:57:37.058667Z","steps":["trace[22116391] 'read index received' (duration: 315.56821ms)","trace[22116391] 'applied index is now lower than readState.Index' (duration: 1.934865ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:37.059088Z","caller":"traceutil/trace.go:171","msg":"trace[13454704] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"337.987166ms","start":"2026-07-17T10:57:36.721071Z","end":"2026-07-17T10:57:37.059058Z","steps":["trace[13454704] 'process raft request' (duration: 337.469874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:37.059248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:36.721048Z","time spent":"338.121211ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:37.059679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.51782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:37.059823Z","caller":"traceutil/trace.go:171","msg":"trace[641920775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:449; }","duration":"318.683613ms","start":"2026-07-17T10:57:36.741118Z","end":"2026-07-17T10:57:37.059802Z","steps":["trace[641920775] 'agreement among raft nodes before linearized reading' (duration: 318.501679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:37.059926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:36.741099Z","time spent":"318.812287ms","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:57:37.060158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.260127ms","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-17T10:57:37.060285Z","caller":"traceutil/trace.go:171","msg":"trace[251081371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:449; }","duration":"110.42898ms","start":"2026-07-17T10:57:36.949832Z","end":"2026-07-17T10:57:37.060261Z","steps":["trace[251081371] 'agreement among raft nodes before linearized reading' (duration: 110.045671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:37.386853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.432813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:37.386975Z","caller":"traceutil/trace.go:171","msg":"trace[1181327164] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"320.312632ms","start":"2026-07-17T10:57:37.066628Z","end":"2026-07-17T10:57:37.38694Z","steps":["trace[1181327164] 'process raft request' (duration: 130.710056ms)","trace[1181327164] 'compare' (duration: 189.297608ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:37.387041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:37.06661Z","time spent":"320.400243ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:37.393753Z","caller":"traceutil/trace.go:171","msg":"trace[391556197] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"306.560545ms","start":"2026-07-17T10:57:37.08716Z","end":"2026-07-17T10:57:37.39372Z","steps":["trace[391556197] 'process raft request' (duration: 306.379431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:37.393885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:37.087136Z","time spent":"306.692599ms","remote":"127.0.0.1:39268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:37.393742Z","caller":"traceutil/trace.go:171","msg":"trace[1124579873] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"306.471142ms","start":"2026-07-17T10:57:37.087237Z","end":"2026-07-17T10:57:37.393708Z","steps":["trace[1124579873] 'process raft request' (duration: 306.421761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:37.394104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:37.087214Z","time spent":"306.783911ms","remote":"127.0.0.1:39268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:37.749534Z","caller":"traceutil/trace.go:171","msg":"trace[1890969647] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"142.913273ms","start":"2026-07-17T10:57:37.606597Z","end":"2026-07-17T10:57:37.74951Z","steps":["trace[1890969647] 'process raft request' (duration: 128.206836ms)","trace[1890969647] 'compare' (duration: 14.522282ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:37.958121Z","caller":"traceutil/trace.go:171","msg":"trace[399919699] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"202.188463ms","start":"2026-07-17T10:57:37.755908Z","end":"2026-07-17T10:57:37.958097Z","steps":["trace[399919699] 'process raft request' (duration: 143.18947ms)","trace[399919699] 'compare' (duration: 58.717596ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:37.958832Z","caller":"traceutil/trace.go:171","msg":"trace[116496542] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"201.294241ms","start":"2026-07-17T10:57:37.757516Z","end":"2026-07-17T10:57:37.95881Z","steps":["trace[116496542] 'process raft request' (duration: 200.40666ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:37.9591Z","caller":"traceutil/trace.go:171","msg":"trace[1710636479] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"201.425495ms","start":"2026-07-17T10:57:37.757661Z","end":"2026-07-17T10:57:37.959086Z","steps":["trace[1710636479] 'process raft request' (duration: 201.354483ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:38.284654Z","caller":"traceutil/trace.go:171","msg":"trace[130528346] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"314.225856ms","start":"2026-07-17T10:57:37.970399Z","end":"2026-07-17T10:57:38.284625Z","steps":["trace[130528346] 'read index received' (duration: 277.588798ms)","trace[130528346] 'applied index is now lower than readState.Index' (duration: 36.635878ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:38.284744Z","caller":"traceutil/trace.go:171","msg":"trace[1140127848] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"316.189013ms","start":"2026-07-17T10:57:37.968513Z","end":"2026-07-17T10:57:38.284702Z","steps":["trace[1140127848] 'process raft request' (duration: 279.35678ms)","trace[1140127848] 'compare' (duration: 36.615478ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:38.284815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.405841ms","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:5"} {"level":"info","ts":"2026-07-17T10:57:38.284853Z","caller":"traceutil/trace.go:171","msg":"trace[1146646995] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:466; }","duration":"314.522073ms","start":"2026-07-17T10:57:37.970316Z","end":"2026-07-17T10:57:38.284839Z","steps":["trace[1146646995] 'agreement among raft nodes before linearized reading' (duration: 314.407141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:38.284881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:37.968491Z","time spent":"316.324116ms","remote":"127.0.0.1:39556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:57:38.28495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:37.970295Z","time spent":"314.643636ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-07-17T10:57:38.509694Z","caller":"traceutil/trace.go:171","msg":"trace[1314149417] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"475.984985ms","start":"2026-07-17T10:57:38.033679Z","end":"2026-07-17T10:57:38.509664Z","steps":["trace[1314149417] 'process raft request' (duration: 425.940318ms)","trace[1314149417] 'compare' (duration: 49.727119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:38.509843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:38.033649Z","time spent":"476.114508ms","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:39.096778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.015479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:39.097082Z","caller":"traceutil/trace.go:171","msg":"trace[1177899252] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"806.954652ms","start":"2026-07-17T10:57:38.29009Z","end":"2026-07-17T10:57:39.097045Z","steps":["trace[1177899252] 'process raft request' (duration: 806.825769ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:39.097132Z","caller":"traceutil/trace.go:171","msg":"trace[1933980617] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:488; }","duration":"509.333565ms","start":"2026-07-17T10:57:38.58778Z","end":"2026-07-17T10:57:39.097114Z","steps":["trace[1933980617] 'read index received' (duration: 177.823505ms)","trace[1933980617] 'applied index is now lower than readState.Index' (duration: 331.50872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:39.097215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:38.290071Z","time spent":"807.080234ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:39.097194Z","caller":"traceutil/trace.go:171","msg":"trace[1380127478] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"804.178466ms","start":"2026-07-17T10:57:38.292988Z","end":"2026-07-17T10:57:39.097166Z","steps":["trace[1380127478] 'process raft request' (duration: 804.055473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:39.097325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:38.29298Z","time spent":"804.272159ms","remote":"127.0.0.1:39534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:39.09747Z","caller":"traceutil/trace.go:171","msg":"trace[1494715863] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"806.44889ms","start":"2026-07-17T10:57:38.290965Z","end":"2026-07-17T10:57:39.097414Z","steps":["trace[1494715863] 'process raft request' (duration: 806.027881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:39.09758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:38.290948Z","time spent":"806.582433ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:39.097713Z","caller":"traceutil/trace.go:171","msg":"trace[163188415] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"809.214226ms","start":"2026-07-17T10:57:38.28779Z","end":"2026-07-17T10:57:39.097004Z","steps":["trace[163188415] 'process raft request' (duration: 477.905071ms)","trace[163188415] 'compare' (duration: 330.581297ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:39.097782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:38.287769Z","time spent":"809.980834ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:57:39.09777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.301951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T10:57:39.097778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.981502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-07-17T10:57:39.097837Z","caller":"traceutil/trace.go:171","msg":"trace[1135225883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"354.418134ms","start":"2026-07-17T10:57:38.743399Z","end":"2026-07-17T10:57:39.097817Z","steps":["trace[1135225883] 'agreement among raft nodes before linearized reading' (duration: 354.30832ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:39.097857Z","caller":"traceutil/trace.go:171","msg":"trace[158074605] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:471; }","duration":"510.099724ms","start":"2026-07-17T10:57:38.587737Z","end":"2026-07-17T10:57:39.097837Z","steps":["trace[158074605] 'agreement among raft nodes before linearized reading' (duration: 509.492479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:39.097882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:38.743372Z","time spent":"354.502746ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:57:39.09791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:38.587715Z","time spent":"510.181257ms","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329972,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-07-17T10:57:40.7217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.550596ms","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-17T10:57:40.721831Z","caller":"traceutil/trace.go:171","msg":"trace[1036900042] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:471; }","duration":"228.696179ms","start":"2026-07-17T10:57:40.493113Z","end":"2026-07-17T10:57:40.721809Z","steps":["trace[1036900042] 'range keys from in-memory index tree' (duration: 228.530616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:40.722039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437964118s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:40.722261Z","caller":"traceutil/trace.go:171","msg":"trace[13087123] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"1.619268816s","start":"2026-07-17T10:57:39.102925Z","end":"2026-07-17T10:57:40.722194Z","steps":["trace[13087123] 'process raft request' (duration: 181.058812ms)","trace[13087123] 'compare' (duration: 1.437830395s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:40.722368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.102903Z","time spent":"1.61940534s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15061,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:40.850781Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.566777259s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T10:57:40.991395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.360927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:40.991815Z","caller":"traceutil/trace.go:171","msg":"trace[797909240] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"1.888392001s","start":"2026-07-17T10:57:39.103389Z","end":"2026-07-17T10:57:40.991781Z","steps":["trace[797909240] 'process raft request' (duration: 1.747570384s)","trace[797909240] 'compare' (duration: 140.221303ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:40.991967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.103379Z","time spent":"1.888505315s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:40.99208Z","caller":"traceutil/trace.go:171","msg":"trace[467926930] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:492; }","duration":"1.886992299s","start":"2026-07-17T10:57:39.105068Z","end":"2026-07-17T10:57:40.99206Z","steps":["trace[467926930] 'read index received' (duration: 178.929022ms)","trace[467926930] 'applied index is now lower than readState.Index' (duration: 1.708061767s)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:40.992131Z","caller":"traceutil/trace.go:171","msg":"trace[194050245] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.888624507s","start":"2026-07-17T10:57:39.103475Z","end":"2026-07-17T10:57:40.9921Z","steps":["trace[194050245] 'process raft request' (duration: 1.888215047s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:40.992574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.103468Z","time spent":"1.889063729s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:40.992202Z","caller":"traceutil/trace.go:171","msg":"trace[490782430] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.888507596s","start":"2026-07-17T10:57:39.103682Z","end":"2026-07-17T10:57:40.99219Z","steps":["trace[490782430] 'process raft request' (duration: 1.888137136s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:40.992851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.103653Z","time spent":"1.88915102s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:40.992212Z","caller":"traceutil/trace.go:171","msg":"trace[1875148497] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.888549476s","start":"2026-07-17T10:57:39.103648Z","end":"2026-07-17T10:57:40.992198Z","steps":["trace[1875148497] 'process raft request' (duration: 1.888098775s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:40.993139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.103635Z","time spent":"1.889378856s","remote":"127.0.0.1:39556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:40.992269Z","caller":"traceutil/trace.go:171","msg":"trace[1256654837] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.883022635s","start":"2026-07-17T10:57:39.10923Z","end":"2026-07-17T10:57:40.992252Z","steps":["trace[1256654837] 'process raft request' (duration: 1.882701197s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:40.992353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.887273196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"warn","ts":"2026-07-17T10:57:40.993255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.109218Z","time spent":"1.883973007s","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:40.993293Z","caller":"traceutil/trace.go:171","msg":"trace[52167069] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:477; }","duration":"1.888246889s","start":"2026-07-17T10:57:39.105028Z","end":"2026-07-17T10:57:40.993275Z","steps":["trace[52167069] 'agreement among raft nodes before linearized reading' (duration: 1.887271936s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:40.993346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.105019Z","time spent":"1.888316651s","remote":"127.0.0.1:39534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4359,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-07-17T10:57:41.223183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.27698ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-17T10:57:41.223509Z","caller":"traceutil/trace.go:171","msg":"trace[1693123452] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:498; }","duration":"231.045418ms","start":"2026-07-17T10:57:40.992444Z","end":"2026-07-17T10:57:41.22349Z","steps":["trace[1693123452] 'read index received' (duration: 80.44983ms)","trace[1693123452] 'applied index is now lower than readState.Index' (duration: 150.594468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:41.223661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.689523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n69bs\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-07-17T10:57:41.223707Z","caller":"traceutil/trace.go:171","msg":"trace[1015476938] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n69bs; range_end:; response_count:1; response_revision:479; }","duration":"494.774326ms","start":"2026-07-17T10:57:40.728921Z","end":"2026-07-17T10:57:41.223696Z","steps":["trace[1015476938] 'agreement among raft nodes before linearized reading' (duration: 494.654433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.223745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:40.728909Z","time spent":"494.828067ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":15100,"request content":"key:\"/registry/pods/kube-system/cilium-n69bs\" "} {"level":"warn","ts":"2026-07-17T10:57:41.223733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66755775s","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-17T10:57:41.224243Z","caller":"traceutil/trace.go:171","msg":"trace[1889803935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"1.668128443s","start":"2026-07-17T10:57:39.556103Z","end":"2026-07-17T10:57:41.224232Z","steps":["trace[1889803935] 'agreement among raft nodes before linearized reading' (duration: 1.667505699s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.224278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.556071Z","time spent":"1.668197185s","remote":"127.0.0.1:39320","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-17T10:57:41.223899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458394589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T10:57:41.224477Z","caller":"traceutil/trace.go:171","msg":"trace[1495085851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"1.458996423s","start":"2026-07-17T10:57:39.765454Z","end":"2026-07-17T10:57:41.224451Z","steps":["trace[1495085851] 'agreement among raft nodes before linearized reading' (duration: 1.458336618s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.22462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.765428Z","time spent":"1.459175408s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T10:57:41.223896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.832943078s","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-17T10:57:41.224877Z","caller":"traceutil/trace.go:171","msg":"trace[2134852889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"1.833928872s","start":"2026-07-17T10:57:39.390936Z","end":"2026-07-17T10:57:41.224865Z","steps":["trace[2134852889] 'agreement among raft nodes before linearized reading' (duration: 1.832883727s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.224976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.390878Z","time spent":"1.834086286s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:57:41.223946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262195386s","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-17T10:57:41.22397Z","caller":"traceutil/trace.go:171","msg":"trace[220469011] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"908.91351ms","start":"2026-07-17T10:57:40.315034Z","end":"2026-07-17T10:57:41.223947Z","steps":["trace[220469011] 'process raft request' (duration: 908.257835ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.223987Z","caller":"traceutil/trace.go:171","msg":"trace[594199284] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"496.873156ms","start":"2026-07-17T10:57:40.727094Z","end":"2026-07-17T10:57:41.223967Z","steps":["trace[594199284] 'process raft request' (duration: 496.337713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.22397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.789607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T10:57:41.224079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029643752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-07-17T10:57:41.225249Z","caller":"traceutil/trace.go:171","msg":"trace[1664764418] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:479; }","duration":"2.030806029s","start":"2026-07-17T10:57:39.194382Z","end":"2026-07-17T10:57:41.225188Z","steps":["trace[1664764418] 'agreement among raft nodes before linearized reading' (duration: 2.029421186s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.225398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.194365Z","time spent":"2.031014125s","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329972,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-07-17T10:57:41.225137Z","caller":"traceutil/trace.go:171","msg":"trace[254164011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"1.263405335s","start":"2026-07-17T10:57:39.961709Z","end":"2026-07-17T10:57:41.225114Z","steps":["trace[254164011] 'agreement among raft nodes before linearized reading' (duration: 1.261914899s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.225655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:40.727069Z","time spent":"498.534276ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:57:41.225703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:39.961693Z","time spent":"1.263936497s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T10:57:41.225812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:40.314997Z","time spent":"910.595371ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:41.225875Z","caller":"traceutil/trace.go:171","msg":"trace[1837670908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"487.005319ms","start":"2026-07-17T10:57:40.738856Z","end":"2026-07-17T10:57:41.225861Z","steps":["trace[1837670908] 'agreement among raft nodes before linearized reading' (duration: 484.647883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.225923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:40.738847Z","time spent":"487.063961ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T10:57:41.230003Z","caller":"traceutil/trace.go:171","msg":"trace[659097662] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"229.241855ms","start":"2026-07-17T10:57:41.000743Z","end":"2026-07-17T10:57:41.229985Z","steps":["trace[659097662] 'process raft request' (duration: 228.893867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.230376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.266155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-07-17T10:57:41.2304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.144361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-07-17T10:57:41.230434Z","caller":"traceutil/trace.go:171","msg":"trace[1026670413] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:481; }","duration":"119.387298ms","start":"2026-07-17T10:57:41.111033Z","end":"2026-07-17T10:57:41.23042Z","steps":["trace[1026670413] 'agreement among raft nodes before linearized reading' (duration: 119.241675ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.230444Z","caller":"traceutil/trace.go:171","msg":"trace[2122396826] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:481; }","duration":"221.232204ms","start":"2026-07-17T10:57:41.009199Z","end":"2026-07-17T10:57:41.230431Z","steps":["trace[2122396826] 'agreement among raft nodes before linearized reading' (duration: 221.117791ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.230481Z","caller":"traceutil/trace.go:171","msg":"trace[261539201] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"228.911958ms","start":"2026-07-17T10:57:41.001492Z","end":"2026-07-17T10:57:41.230404Z","steps":["trace[261539201] 'process raft request' (duration: 228.295323ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.383816Z","caller":"traceutil/trace.go:171","msg":"trace[1855836152] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"153.361713ms","start":"2026-07-17T10:57:41.230434Z","end":"2026-07-17T10:57:41.383796Z","steps":["trace[1855836152] 'process raft request' (duration: 138.65102ms)","trace[1855836152] 'compare' (duration: 14.565819ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:41.384233Z","caller":"traceutil/trace.go:171","msg":"trace[1199255165] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"151.290185ms","start":"2026-07-17T10:57:41.232928Z","end":"2026-07-17T10:57:41.384218Z","steps":["trace[1199255165] 'process raft request' (duration: 151.044308ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.384288Z","caller":"traceutil/trace.go:171","msg":"trace[495058837] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"153.247741ms","start":"2026-07-17T10:57:41.231027Z","end":"2026-07-17T10:57:41.384275Z","steps":["trace[495058837] 'process raft request' (duration: 152.732348ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.384287Z","caller":"traceutil/trace.go:171","msg":"trace[1307691055] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:504; }","duration":"126.918979ms","start":"2026-07-17T10:57:41.257348Z","end":"2026-07-17T10:57:41.384267Z","steps":["trace[1307691055] 'read index received' (duration: 111.787645ms)","trace[1307691055] 'applied index is now lower than readState.Index' (duration: 15.130074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:41.384423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.656944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-qqzml\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-07-17T10:57:41.384484Z","caller":"traceutil/trace.go:171","msg":"trace[672544776] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-qqzml; range_end:; response_count:1; response_revision:488; }","duration":"151.760945ms","start":"2026-07-17T10:57:41.232709Z","end":"2026-07-17T10:57:41.38447Z","steps":["trace[672544776] 'agreement among raft nodes before linearized reading' (duration: 151.626652ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.384531Z","caller":"traceutil/trace.go:171","msg":"trace[163757139] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"144.992433ms","start":"2026-07-17T10:57:41.239526Z","end":"2026-07-17T10:57:41.384519Z","steps":["trace[163757139] 'process raft request' (duration: 144.607223ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.384598Z","caller":"traceutil/trace.go:171","msg":"trace[210383460] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"144.213544ms","start":"2026-07-17T10:57:41.240374Z","end":"2026-07-17T10:57:41.384587Z","steps":["trace[210383460] 'process raft request' (duration: 143.821815ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:41.384697Z","caller":"traceutil/trace.go:171","msg":"trace[1533970283] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"151.672002ms","start":"2026-07-17T10:57:41.233011Z","end":"2026-07-17T10:57:41.384683Z","steps":["trace[1533970283] 'process raft request' (duration: 151.043817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:41.384771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.989421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n69bs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-17T10:57:41.384829Z","caller":"traceutil/trace.go:171","msg":"trace[1290986834] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n69bs; range_end:; response_count:1; response_revision:488; }","duration":"152.057022ms","start":"2026-07-17T10:57:41.232758Z","end":"2026-07-17T10:57:41.384815Z","steps":["trace[1290986834] 'agreement among raft nodes before linearized reading' (duration: 151.95946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:42.243576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037146,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:42.744067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037146,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:42.852809Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.457852251s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T10:57:42.853352Z","caller":"traceutil/trace.go:171","msg":"trace[945547348] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"1.110678092s","start":"2026-07-17T10:57:41.742652Z","end":"2026-07-17T10:57:42.85333Z","steps":["trace[945547348] 'read index received' (duration: 1.110340104s)","trace[945547348] 'applied index is now lower than readState.Index' (duration: 336.788µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:42.853492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110822235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:42.853524Z","caller":"traceutil/trace.go:171","msg":"trace[1650733780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"1.110905597s","start":"2026-07-17T10:57:41.742608Z","end":"2026-07-17T10:57:42.853514Z","steps":["trace[1650733780] 'agreement among raft nodes before linearized reading' (duration: 1.110811295s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:42.853556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:41.742587Z","time spent":"1.110962339s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T10:57:42.853575Z","caller":"traceutil/trace.go:171","msg":"trace[1779255433] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.458680581s","start":"2026-07-17T10:57:41.394839Z","end":"2026-07-17T10:57:42.853519Z","steps":["trace[1779255433] 'process raft request' (duration: 1.458333223s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:42.85395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:41.394818Z","time spent":"1.45905149s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4668,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:43.895382Z","caller":"traceutil/trace.go:171","msg":"trace[964774855] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"158.072208ms","start":"2026-07-17T10:57:43.737283Z","end":"2026-07-17T10:57:43.895355Z","steps":["trace[964774855] 'process raft request' (duration: 118.703674ms)","trace[964774855] 'compare' (duration: 39.19048ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:43.898766Z","caller":"traceutil/trace.go:171","msg":"trace[323293048] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"160.283031ms","start":"2026-07-17T10:57:43.738448Z","end":"2026-07-17T10:57:43.898731Z","steps":["trace[323293048] 'process raft request' (duration: 160.080346ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:44.022061Z","caller":"traceutil/trace.go:171","msg":"trace[2139834840] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"104.224395ms","start":"2026-07-17T10:57:43.917789Z","end":"2026-07-17T10:57:44.022014Z","steps":["trace[2139834840] 'process raft request' (duration: 91.055645ms)","trace[2139834840] 'compare' (duration: 12.78399ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:44.646164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037193,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T10:57:44.835786Z","caller":"traceutil/trace.go:171","msg":"trace[16229537] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"830.80974ms","start":"2026-07-17T10:57:44.004954Z","end":"2026-07-17T10:57:44.835764Z","steps":["trace[16229537] 'process raft request' (duration: 829.832406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:44.835926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:44.004932Z","time spent":"830.917882ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:44.846393Z","caller":"traceutil/trace.go:171","msg":"trace[1131018216] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"813.775315ms","start":"2026-07-17T10:57:44.032593Z","end":"2026-07-17T10:57:44.846368Z","steps":["trace[1131018216] 'process raft request' (duration: 812.606797ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:44.84648Z","caller":"traceutil/trace.go:171","msg":"trace[33786794] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:560; }","duration":"701.250309ms","start":"2026-07-17T10:57:44.145199Z","end":"2026-07-17T10:57:44.846449Z","steps":["trace[33786794] 'read index received' (duration: 689.595317ms)","trace[33786794] 'applied index is now lower than readState.Index' (duration: 11.654202ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:44.846527Z","caller":"traceutil/trace.go:171","msg":"trace[456348308] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"803.29366ms","start":"2026-07-17T10:57:44.043211Z","end":"2026-07-17T10:57:44.846505Z","steps":["trace[456348308] 'process raft request' (duration: 803.073684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:44.846558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:44.032567Z","time spent":"813.879717ms","remote":"127.0.0.1:39596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:57:44.846621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:44.043191Z","time spent":"803.381162ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:44.846643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.437494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T10:57:44.846675Z","caller":"traceutil/trace.go:171","msg":"trace[146454966] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:539; }","duration":"701.521136ms","start":"2026-07-17T10:57:44.145145Z","end":"2026-07-17T10:57:44.846667Z","steps":["trace[146454966] 'agreement among raft nodes before linearized reading' (duration: 701.426854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:44.846701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:44.145123Z","time spent":"701.572208ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-07-17T10:57:44.846692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.108146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:44.846751Z","caller":"traceutil/trace.go:171","msg":"trace[451049800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"105.205869ms","start":"2026-07-17T10:57:44.741529Z","end":"2026-07-17T10:57:44.846735Z","steps":["trace[451049800] 'agreement among raft nodes before linearized reading' (duration: 105.114456ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:44.847042Z","caller":"traceutil/trace.go:171","msg":"trace[1600441279] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"738.894324ms","start":"2026-07-17T10:57:44.107717Z","end":"2026-07-17T10:57:44.846611Z","steps":["trace[1600441279] 'process raft request' (duration: 738.687549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:44.847223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:44.107698Z","time spent":"739.447849ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:46.119433Z","caller":"traceutil/trace.go:171","msg":"trace[1117090242] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"200.107454ms","start":"2026-07-17T10:57:45.919303Z","end":"2026-07-17T10:57:46.11941Z","steps":["trace[1117090242] 'read index received' (duration: 193.256867ms)","trace[1117090242] 'applied index is now lower than readState.Index' (duration: 6.849337ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:46.119486Z","caller":"traceutil/trace.go:171","msg":"trace[959607338] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"203.189961ms","start":"2026-07-17T10:57:45.916251Z","end":"2026-07-17T10:57:46.11944Z","steps":["trace[959607338] 'process raft request' (duration: 196.374074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:46.119639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.315289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-07-17T10:57:46.119682Z","caller":"traceutil/trace.go:171","msg":"trace[921224520] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:574; }","duration":"200.390002ms","start":"2026-07-17T10:57:45.919282Z","end":"2026-07-17T10:57:46.119672Z","steps":["trace[921224520] 'agreement among raft nodes before linearized reading' (duration: 200.190957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:47.885595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.646005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:47.885804Z","caller":"traceutil/trace.go:171","msg":"trace[1830637203] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"531.545283ms","start":"2026-07-17T10:57:47.354154Z","end":"2026-07-17T10:57:47.885699Z","steps":["trace[1830637203] 'read index received' (duration: 293.56431ms)","trace[1830637203] 'applied index is now lower than readState.Index' (duration: 237.979633ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:47.885878Z","caller":"traceutil/trace.go:171","msg":"trace[1537907716] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"582.587868ms","start":"2026-07-17T10:57:47.30326Z","end":"2026-07-17T10:57:47.885848Z","steps":["trace[1537907716] 'process raft request' (duration: 344.583355ms)","trace[1537907716] 'compare' (duration: 237.507622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:47.886015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:47.303245Z","time spent":"582.691781ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:47.886041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.881552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:47.886144Z","caller":"traceutil/trace.go:171","msg":"trace[1339482343] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:577; }","duration":"532.023204ms","start":"2026-07-17T10:57:47.354108Z","end":"2026-07-17T10:57:47.886131Z","steps":["trace[1339482343] 'agreement among raft nodes before linearized reading' (duration: 531.85315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:47.886222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.211415ms","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-17T10:57:47.886259Z","caller":"traceutil/trace.go:171","msg":"trace[215296594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"453.264887ms","start":"2026-07-17T10:57:47.432983Z","end":"2026-07-17T10:57:47.886248Z","steps":["trace[215296594] 'agreement among raft nodes before linearized reading' (duration: 453.153554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:47.886287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:47.432972Z","time spent":"453.308298ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T10:57:47.886261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.233292ms","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-17T10:57:47.88641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.645853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:47.886442Z","caller":"traceutil/trace.go:171","msg":"trace[1346030033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"143.735415ms","start":"2026-07-17T10:57:47.742698Z","end":"2026-07-17T10:57:47.886434Z","steps":["trace[1346030033] 'agreement among raft nodes before linearized reading' (duration: 143.691453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:47.886459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.456359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T10:57:47.886433Z","caller":"traceutil/trace.go:171","msg":"trace[1548279296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"476.5017ms","start":"2026-07-17T10:57:47.409909Z","end":"2026-07-17T10:57:47.886411Z","steps":["trace[1548279296] 'agreement among raft nodes before linearized reading' (duration: 476.189721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:47.886392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:47.354094Z","time spent":"532.130597ms","remote":"127.0.0.1:39268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-07-17T10:57:47.886623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:47.409889Z","time spent":"476.645723ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T10:57:47.886528Z","caller":"traceutil/trace.go:171","msg":"trace[702688437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"474.544161ms","start":"2026-07-17T10:57:47.411965Z","end":"2026-07-17T10:57:47.886509Z","steps":["trace[702688437] 'agreement among raft nodes before linearized reading' (duration: 474.421897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:47.886738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:47.411953Z","time spent":"474.775057ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T10:57:49.049381Z","caller":"traceutil/trace.go:171","msg":"trace[1231025289] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"201.863726ms","start":"2026-07-17T10:57:48.847493Z","end":"2026-07-17T10:57:49.049357Z","steps":["trace[1231025289] 'process raft request' (duration: 200.39961ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:51.989252Z","caller":"traceutil/trace.go:171","msg":"trace[309666725] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"472.824577ms","start":"2026-07-17T10:57:51.5164Z","end":"2026-07-17T10:57:51.989225Z","steps":["trace[309666725] 'process raft request' (duration: 472.273393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:51.98959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:51.516371Z","time spent":"472.9613ms","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:57:52.013929Z","caller":"traceutil/trace.go:171","msg":"trace[987913818] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"115.508202ms","start":"2026-07-17T10:57:51.898398Z","end":"2026-07-17T10:57:52.013906Z","steps":["trace[987913818] 'read index received' (duration: 90.2006ms)","trace[987913818] 'applied index is now lower than readState.Index' (duration: 25.306872ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:52.014131Z","caller":"traceutil/trace.go:171","msg":"trace[670392759] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"400.152895ms","start":"2026-07-17T10:57:51.613972Z","end":"2026-07-17T10:57:52.014125Z","steps":["trace[670392759] 'process raft request' (duration: 399.834806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:52.014214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:51.613945Z","time spent":"400.208335ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:52.014755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.215221ms","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-17T10:57:52.014838Z","caller":"traceutil/trace.go:171","msg":"trace[1823983159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"108.329264ms","start":"2026-07-17T10:57:51.906488Z","end":"2026-07-17T10:57:52.014817Z","steps":["trace[1823983159] 'agreement among raft nodes before linearized reading' (duration: 108.097068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:52.015156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.755344ms","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-17T10:57:52.015179Z","caller":"traceutil/trace.go:171","msg":"trace[1245617797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"116.848456ms","start":"2026-07-17T10:57:51.898324Z","end":"2026-07-17T10:57:52.015173Z","steps":["trace[1245617797] 'agreement among raft nodes before linearized reading' (duration: 116.766064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:52.762636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:52.891159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.749283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:52.891391Z","caller":"traceutil/trace.go:171","msg":"trace[2110370950] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"871.721402ms","start":"2026-07-17T10:57:52.019636Z","end":"2026-07-17T10:57:52.891358Z","steps":["trace[2110370950] 'process raft request' (duration: 750.657761ms)","trace[2110370950] 'compare' (duration: 120.63765ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:52.89145Z","caller":"traceutil/trace.go:171","msg":"trace[400456759] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:630; }","duration":"629.752376ms","start":"2026-07-17T10:57:52.26168Z","end":"2026-07-17T10:57:52.891432Z","steps":["trace[400456759] 'read index received' (duration: 508.707906ms)","trace[400456759] 'applied index is now lower than readState.Index' (duration: 121.04348ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:52.891459Z","caller":"traceutil/trace.go:171","msg":"trace[788887660] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"870.759939ms","start":"2026-07-17T10:57:52.020673Z","end":"2026-07-17T10:57:52.891433Z","steps":["trace[788887660] 'process raft request' (duration: 870.604305ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:52.891506Z","caller":"traceutil/trace.go:171","msg":"trace[2004800722] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"870.79876ms","start":"2026-07-17T10:57:52.020685Z","end":"2026-07-17T10:57:52.891484Z","steps":["trace[2004800722] 'process raft request' (duration: 870.645906ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:52.891533Z","caller":"traceutil/trace.go:171","msg":"trace[94360753] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"840.281196ms","start":"2026-07-17T10:57:52.051238Z","end":"2026-07-17T10:57:52.89152Z","steps":["trace[94360753] 'process raft request' (duration: 840.158083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:52.891557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:52.019612Z","time spent":"871.853116ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:52.891572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:52.020675Z","time spent":"870.864661ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:52.891591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:52.051225Z","time spent":"840.339927ms","remote":"127.0.0.1:39574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:52.891531Z","caller":"traceutil/trace.go:171","msg":"trace[305051351] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"870.756259ms","start":"2026-07-17T10:57:52.020723Z","end":"2026-07-17T10:57:52.891479Z","steps":["trace[305051351] 'process raft request' (duration: 870.643336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:52.891562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:52.020652Z","time spent":"870.870722ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:52.891691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.009282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n69bs\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-17T10:57:52.891716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.587524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:52.891743Z","caller":"traceutil/trace.go:171","msg":"trace[322709764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"149.644897ms","start":"2026-07-17T10:57:52.742089Z","end":"2026-07-17T10:57:52.891734Z","steps":["trace[322709764] 'agreement among raft nodes before linearized reading' (duration: 149.603386ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:52.891737Z","caller":"traceutil/trace.go:171","msg":"trace[1155544043] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n69bs; range_end:; response_count:1; response_revision:608; }","duration":"630.118495ms","start":"2026-07-17T10:57:52.261605Z","end":"2026-07-17T10:57:52.891723Z","steps":["trace[1155544043] 'agreement among raft nodes before linearized reading' (duration: 629.91935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:52.891793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:52.261583Z","time spent":"630.202556ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-n69bs\" "} {"level":"warn","ts":"2026-07-17T10:57:52.89179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:52.020718Z","time spent":"870.929503ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:54.245125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:54.745444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:55.245831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:55.744479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000171834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-17T10:57:55.744563Z","caller":"traceutil/trace.go:171","msg":"trace[861570486] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000304718s","start":"2026-07-17T10:57:53.744239Z","end":"2026-07-17T10:57:55.744543Z","steps":["trace[861570486] 'agreement among raft nodes before linearized reading' (duration: 2.000201115s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:55.744625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:53.744222Z","time spent":"2.000382679s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/17 10:57:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-17T10:57:55.746588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:56.247416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:56.747572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:56.786645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.546037577s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:56.786769Z","caller":"traceutil/trace.go:171","msg":"trace[1897642221] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"3.876926009s","start":"2026-07-17T10:57:52.909819Z","end":"2026-07-17T10:57:56.786745Z","steps":["trace[1897642221] 'process raft request' (duration: 330.693268ms)","trace[1897642221] 'compare' (duration: 3.545899532s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:56.786857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:52.909801Z","time spent":"3.877015451s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:57.248309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:57.744864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000237728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-17T10:57:57.744934Z","caller":"traceutil/trace.go:171","msg":"trace[1864259733] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000350981s","start":"2026-07-17T10:57:55.744568Z","end":"2026-07-17T10:57:57.744919Z","steps":["trace[1864259733] 'agreement among raft nodes before linearized reading' (duration: 2.000260929s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:57.744965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:55.744552Z","time spent":"2.000407442s","remote":"127.0.0.1:57792","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-17T10:57:57.748659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:58.249347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:57:58.399223Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.079344094s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T10:57:58.436651Z","caller":"traceutil/trace.go:171","msg":"trace[917875297] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"5.118148904s","start":"2026-07-17T10:57:53.318446Z","end":"2026-07-17T10:57:58.436595Z","steps":["trace[917875297] 'process raft request' (duration: 5.081383595s)","trace[917875297] 'compare' (duration: 34.744657ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:58.436952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:53.318416Z","time spent":"5.118460391s","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:58.643748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000533186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-17T10:57:58.643828Z","caller":"traceutil/trace.go:171","msg":"trace[982756980] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000676759s","start":"2026-07-17T10:57:56.643132Z","end":"2026-07-17T10:57:58.643809Z","steps":["trace[982756980] 'agreement among raft nodes before linearized reading' (duration: 2.000574277s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:58.644123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:56.643108Z","time spent":"2.000997748s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/17 10:57:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-17T10:57:58.819247Z","caller":"traceutil/trace.go:171","msg":"trace[1111977788] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:636; }","duration":"5.07491249s","start":"2026-07-17T10:57:53.744278Z","end":"2026-07-17T10:57:58.819191Z","steps":["trace[1111977788] 'read index received' (duration: 4.655320843s)","trace[1111977788] 'applied index is now lower than readState.Index' (duration: 419.590337ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:58.819344Z","caller":"traceutil/trace.go:171","msg":"trace[501280496] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.070530419s","start":"2026-07-17T10:57:57.748797Z","end":"2026-07-17T10:57:58.819327Z","steps":["trace[501280496] 'process raft request' (duration: 1.070304093s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:58.819352Z","caller":"traceutil/trace.go:171","msg":"trace[2037105698] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.219296983s","start":"2026-07-17T10:57:57.600023Z","end":"2026-07-17T10:57:58.81932Z","steps":["trace[2037105698] 'process raft request' (duration: 1.19609087s)","trace[2037105698] 'compare' (duration: 22.384011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:58.819453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:57.748778Z","time spent":"1.070650641s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:57:58.819472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:57.599997Z","time spent":"1.219404946s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:57:59.036104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.787476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:57:59.036469Z","caller":"traceutil/trace.go:171","msg":"trace[746921315] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"540.634878ms","start":"2026-07-17T10:57:58.495798Z","end":"2026-07-17T10:57:59.036433Z","steps":["trace[746921315] 'process raft request' (duration: 437.434982ms)","trace[746921315] 'compare' (duration: 102.649033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:59.036626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:58.495777Z","time spent":"540.764451ms","remote":"127.0.0.1:39300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T10:57:59.036668Z","caller":"traceutil/trace.go:171","msg":"trace[1772557904] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"213.330106ms","start":"2026-07-17T10:57:58.823255Z","end":"2026-07-17T10:57:59.036585Z","steps":["trace[1772557904] 'process raft request' (duration: 213.130001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.036901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.138431672s","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-17T10:57:59.036945Z","caller":"traceutil/trace.go:171","msg":"trace[806659015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"4.138505144s","start":"2026-07-17T10:57:54.898431Z","end":"2026-07-17T10:57:59.036936Z","steps":["trace[806659015] 'agreement among raft nodes before linearized reading' (duration: 4.138333559s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.036971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:54.898418Z","time spent":"4.138545625s","remote":"127.0.0.1:39320","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-17T10:57:59.037107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.742211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T10:57:59.037132Z","caller":"traceutil/trace.go:171","msg":"trace[1887478202] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:614; }","duration":"444.770591ms","start":"2026-07-17T10:57:58.592355Z","end":"2026-07-17T10:57:59.037126Z","steps":["trace[1887478202] 'agreement among raft nodes before linearized reading' (duration: 444.72681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.037152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:58.592284Z","time spent":"444.862875ms","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:57:59.037251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.667419ms","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-17T10:57:59.037276Z","caller":"traceutil/trace.go:171","msg":"trace[935009351] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:614; }","duration":"685.721451ms","start":"2026-07-17T10:57:58.351547Z","end":"2026-07-17T10:57:59.037268Z","steps":["trace[935009351] 'agreement among raft nodes before linearized reading' (duration: 685.68538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.037294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:58.351531Z","time spent":"685.758882ms","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:57:59.037395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284256415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:59.037419Z","caller":"traceutil/trace.go:171","msg":"trace[2015333527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"1.284315657s","start":"2026-07-17T10:57:57.753098Z","end":"2026-07-17T10:57:59.037414Z","steps":["trace[2015333527] 'agreement among raft nodes before linearized reading' (duration: 1.284280686s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.037435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:57.753077Z","time spent":"1.284354378s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:57:59.037591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.14144385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T10:57:59.037642Z","caller":"traceutil/trace.go:171","msg":"trace[1285240426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"4.141558922s","start":"2026-07-17T10:57:54.89607Z","end":"2026-07-17T10:57:59.037629Z","steps":["trace[1285240426] 'agreement among raft nodes before linearized reading' (duration: 4.141290035s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.037579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.141637064s","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-17T10:57:59.03774Z","caller":"traceutil/trace.go:171","msg":"trace[1107357252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"4.141845139s","start":"2026-07-17T10:57:54.895879Z","end":"2026-07-17T10:57:59.037724Z","steps":["trace[1107357252] 'agreement among raft nodes before linearized reading' (duration: 4.141507201s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.037814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:54.895859Z","time spent":"4.141942551s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T10:57:59.037695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:54.896033Z","time spent":"4.141653874s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T10:57:59.037952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.140857254s","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-17T10:57:59.038134Z","caller":"traceutil/trace.go:171","msg":"trace[1640775698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"4.141087269s","start":"2026-07-17T10:57:54.897037Z","end":"2026-07-17T10:57:59.038125Z","steps":["trace[1640775698] 'agreement among raft nodes before linearized reading' (duration: 4.140672289s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.038166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:54.897014Z","time spent":"4.141144081s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T10:57:59.038535Z","caller":"traceutil/trace.go:171","msg":"trace[923864232] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"217.334798ms","start":"2026-07-17T10:57:58.819317Z","end":"2026-07-17T10:57:59.036652Z","steps":["trace[923864232] 'read index received' (duration: 113.926896ms)","trace[923864232] 'applied index is now lower than readState.Index' (duration: 103.403742ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:57:59.038617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.893244685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T10:57:59.038807Z","caller":"traceutil/trace.go:171","msg":"trace[1950192924] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:614; }","duration":"4.893506432s","start":"2026-07-17T10:57:54.145284Z","end":"2026-07-17T10:57:59.03879Z","steps":["trace[1950192924] 'agreement among raft nodes before linearized reading' (duration: 4.89309333s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.038882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:57:54.145259Z","time spent":"4.893606004s","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"info","ts":"2026-07-17T10:57:59.353586Z","caller":"traceutil/trace.go:171","msg":"trace[1090804787] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"220.603257ms","start":"2026-07-17T10:57:59.132958Z","end":"2026-07-17T10:57:59.353562Z","steps":["trace[1090804787] 'process raft request' (duration: 217.936498ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:59.441072Z","caller":"traceutil/trace.go:171","msg":"trace[1507548835] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"154.192876ms","start":"2026-07-17T10:57:59.286855Z","end":"2026-07-17T10:57:59.441048Z","steps":["trace[1507548835] 'process raft request' (duration: 154.066783ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:59.580704Z","caller":"traceutil/trace.go:171","msg":"trace[2108160086] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"221.086259ms","start":"2026-07-17T10:57:59.359596Z","end":"2026-07-17T10:57:59.580682Z","steps":["trace[2108160086] 'process raft request' (duration: 220.824803ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:59.580798Z","caller":"traceutil/trace.go:171","msg":"trace[993236000] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"220.655748ms","start":"2026-07-17T10:57:59.360103Z","end":"2026-07-17T10:57:59.580759Z","steps":["trace[993236000] 'process raft request' (duration: 220.433713ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:59.58088Z","caller":"traceutil/trace.go:171","msg":"trace[771097971] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"220.647817ms","start":"2026-07-17T10:57:59.3602Z","end":"2026-07-17T10:57:59.580848Z","steps":["trace[771097971] 'process raft request' (duration: 220.399271ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:59.581443Z","caller":"traceutil/trace.go:171","msg":"trace[127870650] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:648; }","duration":"216.8263ms","start":"2026-07-17T10:57:59.3646Z","end":"2026-07-17T10:57:59.581426Z","steps":["trace[127870650] 'read index received' (duration: 76.384396ms)","trace[127870650] 'applied index is now lower than readState.Index' (duration: 140.441014ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:57:59.581486Z","caller":"traceutil/trace.go:171","msg":"trace[535890812] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"221.14742ms","start":"2026-07-17T10:57:59.360322Z","end":"2026-07-17T10:57:59.581469Z","steps":["trace[535890812] 'process raft request' (duration: 220.305749ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:57:59.58159Z","caller":"traceutil/trace.go:171","msg":"trace[745493718] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"210.984499ms","start":"2026-07-17T10:57:59.37059Z","end":"2026-07-17T10:57:59.581575Z","steps":["trace[745493718] 'process raft request' (duration: 210.782274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:57:59.581834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.19749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:57:59.58188Z","caller":"traceutil/trace.go:171","msg":"trace[630096968] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:626; }","duration":"217.287442ms","start":"2026-07-17T10:57:59.364577Z","end":"2026-07-17T10:57:59.581864Z","steps":["trace[630096968] 'agreement among raft nodes before linearized reading' (duration: 216.914761ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:01.960195Z","caller":"traceutil/trace.go:171","msg":"trace[119665666] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"339.448918ms","start":"2026-07-17T10:58:01.620721Z","end":"2026-07-17T10:58:01.96017Z","steps":["trace[119665666] 'read index received' (duration: 339.197632ms)","trace[119665666] 'applied index is now lower than readState.Index' (duration: 250.326µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:01.960263Z","caller":"traceutil/trace.go:171","msg":"trace[1690282196] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"471.23931ms","start":"2026-07-17T10:58:01.489003Z","end":"2026-07-17T10:58:01.960243Z","steps":["trace[1690282196] 'process raft request' (duration: 470.963713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:01.960402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:01.488976Z","time spent":"471.369814ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:58:01.960574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.839498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:01.960804Z","caller":"traceutil/trace.go:171","msg":"trace[1755182611] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:656; }","duration":"340.100866ms","start":"2026-07-17T10:58:01.620682Z","end":"2026-07-17T10:58:01.960783Z","steps":["trace[1755182611] 'agreement among raft nodes before linearized reading' (duration: 339.592792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:01.961252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:01.620664Z","time spent":"340.562237ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-17T10:58:01.963616Z","caller":"traceutil/trace.go:171","msg":"trace[2121674038] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"141.691118ms","start":"2026-07-17T10:58:01.8219Z","end":"2026-07-17T10:58:01.963591Z","steps":["trace[2121674038] 'process raft request' (duration: 141.568145ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:03.601232Z","caller":"traceutil/trace.go:171","msg":"trace[296587779] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"275.886152ms","start":"2026-07-17T10:58:03.325314Z","end":"2026-07-17T10:58:03.6012Z","steps":["trace[296587779] 'process raft request' (duration: 275.702488ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:03.608764Z","caller":"traceutil/trace.go:171","msg":"trace[1661122105] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:710; }","duration":"187.375191ms","start":"2026-07-17T10:58:03.421359Z","end":"2026-07-17T10:58:03.608734Z","steps":["trace[1661122105] 'read index received' (duration: 179.723962ms)","trace[1661122105] 'applied index is now lower than readState.Index' (duration: 7.649279ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:03.608954Z","caller":"traceutil/trace.go:171","msg":"trace[2110650075] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"275.199694ms","start":"2026-07-17T10:58:03.333714Z","end":"2026-07-17T10:58:03.608914Z","steps":["trace[2110650075] 'process raft request' (duration: 274.781633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:03.609168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.149299ms","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-17T10:58:03.609242Z","caller":"traceutil/trace.go:171","msg":"trace[837772466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"174.295743ms","start":"2026-07-17T10:58:03.434918Z","end":"2026-07-17T10:58:03.609214Z","steps":["trace[837772466] 'agreement among raft nodes before linearized reading' (duration: 174.076977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:03.609732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.855023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-17T10:58:03.609791Z","caller":"traceutil/trace.go:171","msg":"trace[45268507] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"188.491519ms","start":"2026-07-17T10:58:03.421279Z","end":"2026-07-17T10:58:03.609771Z","steps":["trace[45268507] 'agreement among raft nodes before linearized reading' (duration: 187.638507ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:03.977678Z","caller":"traceutil/trace.go:171","msg":"trace[841384249] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"153.874804ms","start":"2026-07-17T10:58:03.823779Z","end":"2026-07-17T10:58:03.977654Z","steps":["trace[841384249] 'process raft request' (duration: 153.75234ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:04.952186Z","caller":"traceutil/trace.go:171","msg":"trace[332844227] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"132.275683ms","start":"2026-07-17T10:58:04.819854Z","end":"2026-07-17T10:58:04.95213Z","steps":["trace[332844227] 'read index received' (duration: 131.207915ms)","trace[332844227] 'applied index is now lower than readState.Index' (duration: 1.066138ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:04.952464Z","caller":"traceutil/trace.go:171","msg":"trace[537693318] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"150.096906ms","start":"2026-07-17T10:58:04.802335Z","end":"2026-07-17T10:58:04.952432Z","steps":["trace[537693318] 'process raft request' (duration: 148.849803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:04.956295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.64217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-07-17T10:58:04.956494Z","caller":"traceutil/trace.go:171","msg":"trace[1080890859] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:699; }","duration":"136.603555ms","start":"2026-07-17T10:58:04.819838Z","end":"2026-07-17T10:58:04.956442Z","steps":["trace[1080890859] 'agreement among raft nodes before linearized reading' (duration: 132.431207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:05.297548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-17T10:58:05.297634Z","caller":"traceutil/trace.go:171","msg":"trace[232925444] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:699; }","duration":"293.588352ms","start":"2026-07-17T10:58:05.004029Z","end":"2026-07-17T10:58:05.297617Z","steps":["trace[232925444] 'range keys from in-memory index tree' (duration: 293.398747ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:05.452263Z","caller":"traceutil/trace.go:171","msg":"trace[1912459892] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"430.3549ms","start":"2026-07-17T10:58:05.021889Z","end":"2026-07-17T10:58:05.452244Z","steps":["trace[1912459892] 'process raft request' (duration: 430.131425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:05.452495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.021867Z","time spent":"430.454993ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:58:05.928769Z","caller":"traceutil/trace.go:171","msg":"trace[142514] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:729; }","duration":"789.91732ms","start":"2026-07-17T10:58:05.138827Z","end":"2026-07-17T10:58:05.928744Z","steps":["trace[142514] 'read index received' (duration: 313.250713ms)","trace[142514] 'applied index is now lower than readState.Index' (duration: 476.665027ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:58:05.929046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.200407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-17T10:58:05.929088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.248888ms","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-17T10:58:05.929113Z","caller":"traceutil/trace.go:171","msg":"trace[1870152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"790.31423ms","start":"2026-07-17T10:58:05.138781Z","end":"2026-07-17T10:58:05.929096Z","steps":["trace[1870152] 'agreement among raft nodes before linearized reading' (duration: 790.088794ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:05.929137Z","caller":"traceutil/trace.go:171","msg":"trace[453799357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:701; }","duration":"790.338251ms","start":"2026-07-17T10:58:05.138781Z","end":"2026-07-17T10:58:05.92912Z","steps":["trace[453799357] 'agreement among raft nodes before linearized reading' (duration: 790.175946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:05.929169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.138761Z","time spent":"790.400133ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T10:58:05.929168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.138761Z","time spent":"790.394983ms","remote":"127.0.0.1:39320","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-17T10:58:05.92885Z","caller":"traceutil/trace.go:171","msg":"trace[794505078] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"880.724503ms","start":"2026-07-17T10:58:05.048089Z","end":"2026-07-17T10:58:05.928814Z","steps":["trace[794505078] 'process raft request' (duration: 880.499036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:05.929613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.048057Z","time spent":"881.502553ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T10:58:06.161911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.824685Z","time spent":"337.217918ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-17T10:58:06.161966Z","caller":"traceutil/trace.go:171","msg":"trace[656764560] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:731; }","duration":"232.982394ms","start":"2026-07-17T10:58:05.92896Z","end":"2026-07-17T10:58:06.161942Z","steps":["trace[656764560] 'read index received' (duration: 176.009936ms)","trace[656764560] 'applied index is now lower than readState.Index' (duration: 56.971428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:58:06.162105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.930854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T10:58:06.1621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.088668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:06.162202Z","caller":"traceutil/trace.go:171","msg":"trace[301496412] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:702; }","duration":"779.078618ms","start":"2026-07-17T10:58:05.3831Z","end":"2026-07-17T10:58:06.162179Z","steps":["trace[301496412] 'agreement among raft nodes before linearized reading' (duration: 778.932784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.16227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.383078Z","time spent":"779.17818ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 "} {"level":"warn","ts":"2026-07-17T10:58:06.16232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.671073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:06.162404Z","caller":"traceutil/trace.go:171","msg":"trace[1784571986] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:702; }","duration":"779.762675ms","start":"2026-07-17T10:58:05.382614Z","end":"2026-07-17T10:58:06.162376Z","steps":["trace[1784571986] 'agreement among raft nodes before linearized reading' (duration: 779.685413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.162459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.382592Z","time spent":"779.851267ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-07-17T10:58:06.162142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022596476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-17T10:58:06.162275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.341634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:06.162321Z","caller":"traceutil/trace.go:171","msg":"trace[1529006770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"419.280833ms","start":"2026-07-17T10:58:05.742988Z","end":"2026-07-17T10:58:06.162268Z","steps":["trace[1529006770] 'agreement among raft nodes before linearized reading' (duration: 419.062817ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.162582Z","caller":"traceutil/trace.go:171","msg":"trace[212468627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"1.023069498s","start":"2026-07-17T10:58:05.139495Z","end":"2026-07-17T10:58:06.162564Z","steps":["trace[212468627] 'agreement among raft nodes before linearized reading' (duration: 1.022530034s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.162637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.139482Z","time spent":"1.02313954s","remote":"127.0.0.1:39320","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-17T10:58:06.162662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.74292Z","time spent":"419.693205ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:58:06.162689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018339626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T10:58:06.162726Z","caller":"traceutil/trace.go:171","msg":"trace[2105449294] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:702; }","duration":"1.018396087s","start":"2026-07-17T10:58:05.144321Z","end":"2026-07-17T10:58:06.162717Z","steps":["trace[2105449294] 'agreement among raft nodes before linearized reading' (duration: 1.018335256s)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.162687Z","caller":"traceutil/trace.go:171","msg":"trace[331257868] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:702; }","duration":"779.718675ms","start":"2026-07-17T10:58:05.382903Z","end":"2026-07-17T10:58:06.162622Z","steps":["trace[331257868] 'agreement among raft nodes before linearized reading' (duration: 779.337894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.16277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.144312Z","time spent":"1.018448038s","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:58:06.16282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.751488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-07-17T10:58:06.162836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.382895Z","time spent":"779.889029ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-07-17T10:58:06.162913Z","caller":"traceutil/trace.go:171","msg":"trace[192208272] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"942.903542ms","start":"2026-07-17T10:58:05.219989Z","end":"2026-07-17T10:58:06.162892Z","steps":["trace[192208272] 'agreement among raft nodes before linearized reading' (duration: 942.616964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.162932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021092557s","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-17T10:58:06.162963Z","caller":"traceutil/trace.go:171","msg":"trace[1312019903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"1.021142128s","start":"2026-07-17T10:58:05.141812Z","end":"2026-07-17T10:58:06.162954Z","steps":["trace[1312019903] 'agreement among raft nodes before linearized reading' (duration: 1.021055056s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.163066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.691442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-07-17T10:58:06.163001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.141789Z","time spent":"1.021205999s","remote":"127.0.0.1:39320","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-17T10:58:06.163102Z","caller":"traceutil/trace.go:171","msg":"trace[1337966092] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"336.228531ms","start":"2026-07-17T10:58:05.826842Z","end":"2026-07-17T10:58:06.163071Z","steps":["trace[1337966092] 'process raft request' (duration: 334.957058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.163168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.565857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T10:58:06.162968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.219967Z","time spent":"942.990984ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24268,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-07-17T10:58:06.163204Z","caller":"traceutil/trace.go:171","msg":"trace[1118254468] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:702; }","duration":"888.631709ms","start":"2026-07-17T10:58:05.274563Z","end":"2026-07-17T10:58:06.163195Z","steps":["trace[1118254468] 'agreement among raft nodes before linearized reading' (duration: 888.577298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.163213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.826822Z","time spent":"336.329884ms","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:58:06.163241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.274545Z","time spent":"888.68743ms","remote":"127.0.0.1:39154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":5,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-07-17T10:58:06.163188Z","caller":"traceutil/trace.go:171","msg":"trace[136910809] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"742.935888ms","start":"2026-07-17T10:58:05.420174Z","end":"2026-07-17T10:58:06.16311Z","steps":["trace[136910809] 'agreement among raft nodes before linearized reading' (duration: 742.25243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.163119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.097441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n69bs\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-07-17T10:58:06.163383Z","caller":"traceutil/trace.go:171","msg":"trace[1321599914] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n69bs; range_end:; response_count:1; response_revision:702; }","duration":"821.388939ms","start":"2026-07-17T10:58:05.341984Z","end":"2026-07-17T10:58:06.163373Z","steps":["trace[1321599914] 'agreement among raft nodes before linearized reading' (duration: 821.007219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.163423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.341966Z","time spent":"821.447491ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18540,"request content":"key:\"/registry/pods/kube-system/cilium-n69bs\" "} {"level":"warn","ts":"2026-07-17T10:58:06.163498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.575397ms","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-17T10:58:06.163522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.194533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-17T10:58:06.163545Z","caller":"traceutil/trace.go:171","msg":"trace[1329021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:702; }","duration":"541.651458ms","start":"2026-07-17T10:58:05.621883Z","end":"2026-07-17T10:58:06.163534Z","steps":["trace[1329021] 'agreement among raft nodes before linearized reading' (duration: 541.536075ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.163564Z","caller":"traceutil/trace.go:171","msg":"trace[1258474343] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"343.251634ms","start":"2026-07-17T10:58:05.820298Z","end":"2026-07-17T10:58:06.16355Z","steps":["trace[1258474343] 'agreement among raft nodes before linearized reading' (duration: 343.07657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.163585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.621862Z","time spent":"541.71333ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T10:58:06.163595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.820283Z","time spent":"343.304145ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188276,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-17T10:58:06.16346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.420163Z","time spent":"743.177174ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-17T10:58:06.163777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.116716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-17T10:58:06.163827Z","caller":"traceutil/trace.go:171","msg":"trace[1941398012] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"543.196087ms","start":"2026-07-17T10:58:05.620614Z","end":"2026-07-17T10:58:06.163811Z","steps":["trace[1941398012] 'agreement among raft nodes before linearized reading' (duration: 542.91073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.163932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.620595Z","time spent":"543.326963ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-07-17T10:58:06.2507Z","caller":"traceutil/trace.go:171","msg":"trace[936667115] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"317.614714ms","start":"2026-07-17T10:58:05.933059Z","end":"2026-07-17T10:58:06.250674Z","steps":["trace[936667115] 'process raft request' (duration: 317.380417ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.250933Z","caller":"traceutil/trace.go:171","msg":"trace[1875219698] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"317.48187ms","start":"2026-07-17T10:58:05.93343Z","end":"2026-07-17T10:58:06.250911Z","steps":["trace[1875219698] 'process raft request' (duration: 317.1346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.251085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.933419Z","time spent":"317.587644ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:58:06.250892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:05.933042Z","time spent":"317.734887ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:58:06.251773Z","caller":"traceutil/trace.go:171","msg":"trace[1617316441] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"236.594625ms","start":"2026-07-17T10:58:06.015164Z","end":"2026-07-17T10:58:06.251758Z","steps":["trace[1617316441] 'process raft request' (duration: 236.39347ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.337924Z","caller":"traceutil/trace.go:171","msg":"trace[1813307531] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"173.696003ms","start":"2026-07-17T10:58:06.164198Z","end":"2026-07-17T10:58:06.337894Z","steps":["trace[1813307531] 'process raft request' (duration: 148.386112ms)","trace[1813307531] 'compare' (duration: 25.083595ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:06.338113Z","caller":"traceutil/trace.go:171","msg":"trace[751407687] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:733; }","duration":"171.585759ms","start":"2026-07-17T10:58:06.166505Z","end":"2026-07-17T10:58:06.33809Z","steps":["trace[751407687] 'read index received' (duration: 83.88184ms)","trace[751407687] 'applied index is now lower than readState.Index' (duration: 87.702989ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:06.338213Z","caller":"traceutil/trace.go:171","msg":"trace[1068276093] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"163.725573ms","start":"2026-07-17T10:58:06.174478Z","end":"2026-07-17T10:58:06.338204Z","steps":["trace[1068276093] 'process raft request' (duration: 163.520907ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.338507Z","caller":"traceutil/trace.go:171","msg":"trace[987936369] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"165.583621ms","start":"2026-07-17T10:58:06.172911Z","end":"2026-07-17T10:58:06.338494Z","steps":["trace[987936369] 'process raft request' (duration: 165.032597ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.338502Z","caller":"traceutil/trace.go:171","msg":"trace[1906020080] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"167.029599ms","start":"2026-07-17T10:58:06.171457Z","end":"2026-07-17T10:58:06.338486Z","steps":["trace[1906020080] 'process raft request' (duration: 166.417863ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.33899Z","caller":"traceutil/trace.go:171","msg":"trace[300668779] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"160.846638ms","start":"2026-07-17T10:58:06.177483Z","end":"2026-07-17T10:58:06.338329Z","steps":["trace[300668779] 'process raft request' (duration: 160.55353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.339575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.045927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:06.339643Z","caller":"traceutil/trace.go:171","msg":"trace[853380992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"173.155009ms","start":"2026-07-17T10:58:06.166478Z","end":"2026-07-17T10:58:06.339633Z","steps":["trace[853380992] 'agreement among raft nodes before linearized reading' (duration: 171.932577ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.338381Z","caller":"traceutil/trace.go:171","msg":"trace[1325398068] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"168.22967ms","start":"2026-07-17T10:58:06.170125Z","end":"2026-07-17T10:58:06.338355Z","steps":["trace[1325398068] 'process raft request' (duration: 167.684767ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.489291Z","caller":"traceutil/trace.go:171","msg":"trace[1655162070] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"151.102623ms","start":"2026-07-17T10:58:06.338174Z","end":"2026-07-17T10:58:06.489276Z","steps":["trace[1655162070] 'read index received' (duration: 122.458345ms)","trace[1655162070] 'applied index is now lower than readState.Index' (duration: 28.643498ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:06.489398Z","caller":"traceutil/trace.go:171","msg":"trace[2129485814] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"155.145589ms","start":"2026-07-17T10:58:06.334208Z","end":"2026-07-17T10:58:06.489354Z","steps":["trace[2129485814] 'process raft request' (duration: 126.440139ms)","trace[2129485814] 'compare' (duration: 28.515875ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:58:06.489433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.654954ms","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-17T10:58:06.489586Z","caller":"traceutil/trace.go:171","msg":"trace[983164858] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:712; }","duration":"234.88683ms","start":"2026-07-17T10:58:06.25469Z","end":"2026-07-17T10:58:06.489577Z","steps":["trace[983164858] 'agreement among raft nodes before linearized reading' (duration: 234.668374ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.494508Z","caller":"traceutil/trace.go:171","msg":"trace[869423113] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"148.744532ms","start":"2026-07-17T10:58:06.345744Z","end":"2026-07-17T10:58:06.494488Z","steps":["trace[869423113] 'process raft request' (duration: 148.416233ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.494842Z","caller":"traceutil/trace.go:171","msg":"trace[1566094948] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"147.712925ms","start":"2026-07-17T10:58:06.347114Z","end":"2026-07-17T10:58:06.494827Z","steps":["trace[1566094948] 'process raft request' (duration: 147.203042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:06.494946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.789399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n69bs\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-07-17T10:58:06.495023Z","caller":"traceutil/trace.go:171","msg":"trace[1145400444] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n69bs; range_end:; response_count:1; response_revision:715; }","duration":"149.884221ms","start":"2026-07-17T10:58:06.345118Z","end":"2026-07-17T10:58:06.495002Z","steps":["trace[1145400444] 'agreement among raft nodes before linearized reading' (duration: 149.550953ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:06.495205Z","caller":"traceutil/trace.go:171","msg":"trace[845578208] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"149.784669ms","start":"2026-07-17T10:58:06.344612Z","end":"2026-07-17T10:58:06.494396Z","steps":["trace[845578208] 'process raft request' (duration: 149.426319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:16.005417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:58:16.505687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727676253037760,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T10:58:16.579059Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185203916s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T10:58:16.579477Z","caller":"traceutil/trace.go:171","msg":"trace[1382872196] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"1.074505715s","start":"2026-07-17T10:58:15.504943Z","end":"2026-07-17T10:58:16.579448Z","steps":["trace[1382872196] 'read index received' (duration: 1.074292049s)","trace[1382872196] 'applied index is now lower than readState.Index' (duration: 212.876µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:16.579664Z","caller":"traceutil/trace.go:171","msg":"trace[1860459895] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"1.185824344s","start":"2026-07-17T10:58:15.393729Z","end":"2026-07-17T10:58:16.579553Z","steps":["trace[1860459895] 'process raft request' (duration: 1.185573106s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:16.579685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.749326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:16.579739Z","caller":"traceutil/trace.go:171","msg":"trace[1725476731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"836.864019ms","start":"2026-07-17T10:58:15.742861Z","end":"2026-07-17T10:58:16.579725Z","steps":["trace[1725476731] 'agreement among raft nodes before linearized reading' (duration: 836.739256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:16.579779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:15.742839Z","time spent":"836.93218ms","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T10:58:16.579825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:15.393705Z","time spent":"1.185996438s","remote":"127.0.0.1:39300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:58:16.580048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.042931ms","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-17T10:58:16.580077Z","caller":"traceutil/trace.go:171","msg":"trace[1500975680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"180.127594ms","start":"2026-07-17T10:58:16.399939Z","end":"2026-07-17T10:58:16.580067Z","steps":["trace[1500975680] 'agreement among raft nodes before linearized reading' (duration: 180.001631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:16.580176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.881358ms","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-17T10:58:16.580206Z","caller":"traceutil/trace.go:171","msg":"trace[10109050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"191.947289ms","start":"2026-07-17T10:58:16.388249Z","end":"2026-07-17T10:58:16.580196Z","steps":["trace[10109050] 'agreement among raft nodes before linearized reading' (duration: 191.855567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:16.580302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075355638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T10:58:16.58033Z","caller":"traceutil/trace.go:171","msg":"trace[105546549] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:780; }","duration":"1.07542252s","start":"2026-07-17T10:58:15.5049Z","end":"2026-07-17T10:58:16.580322Z","steps":["trace[105546549] 'agreement among raft nodes before linearized reading' (duration: 1.075349098s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:16.580359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:15.504882Z","time spent":"1.075469911s","remote":"127.0.0.1:39268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-17T10:58:16.580489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.349628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-17T10:58:16.580455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.879046ms","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-17T10:58:16.580529Z","caller":"traceutil/trace.go:171","msg":"trace[1939169610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"284.434581ms","start":"2026-07-17T10:58:16.296084Z","end":"2026-07-17T10:58:16.580519Z","steps":["trace[1939169610] 'agreement among raft nodes before linearized reading' (duration: 284.310438ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:16.580554Z","caller":"traceutil/trace.go:171","msg":"trace[556176488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"177.02357ms","start":"2026-07-17T10:58:16.403507Z","end":"2026-07-17T10:58:16.580531Z","steps":["trace[556176488] 'agreement among raft nodes before linearized reading' (duration: 176.753683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:16.580819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.765889ms","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-17T10:58:16.580852Z","caller":"traceutil/trace.go:171","msg":"trace[598551140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"287.860602ms","start":"2026-07-17T10:58:16.292981Z","end":"2026-07-17T10:58:16.580842Z","steps":["trace[598551140] 'agreement among raft nodes before linearized reading' (duration: 287.76615ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:30.786473Z","caller":"traceutil/trace.go:171","msg":"trace[1231025740] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"125.981739ms","start":"2026-07-17T10:58:30.660468Z","end":"2026-07-17T10:58:30.78645Z","steps":["trace[1231025740] 'process raft request' (duration: 125.514476ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:37.10667Z","caller":"traceutil/trace.go:171","msg":"trace[1170170215] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"211.27789ms","start":"2026-07-17T10:58:36.895359Z","end":"2026-07-17T10:58:37.106637Z","steps":["trace[1170170215] 'process raft request' (duration: 148.528548ms)","trace[1170170215] 'compare' (duration: 62.622208ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:37.141755Z","caller":"traceutil/trace.go:171","msg":"trace[1930875274] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"245.685455ms","start":"2026-07-17T10:58:36.896035Z","end":"2026-07-17T10:58:37.14172Z","steps":["trace[1930875274] 'process raft request' (duration: 245.50579ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:37.141829Z","caller":"traceutil/trace.go:171","msg":"trace[1473812965] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"245.467569ms","start":"2026-07-17T10:58:36.896342Z","end":"2026-07-17T10:58:37.14181Z","steps":["trace[1473812965] 'process raft request' (duration: 245.324955ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:37.141756Z","caller":"traceutil/trace.go:171","msg":"trace[903862616] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"245.594142ms","start":"2026-07-17T10:58:36.896141Z","end":"2026-07-17T10:58:37.141735Z","steps":["trace[903862616] 'process raft request' (duration: 245.50297ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:58:53.309475Z","caller":"traceutil/trace.go:171","msg":"trace[36433931] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"127.887434ms","start":"2026-07-17T10:58:53.181571Z","end":"2026-07-17T10:58:53.309459Z","steps":["trace[36433931] 'read index received' (duration: 127.773171ms)","trace[36433931] 'applied index is now lower than readState.Index' (duration: 113.723µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:53.309531Z","caller":"traceutil/trace.go:171","msg":"trace[1286911619] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"332.242237ms","start":"2026-07-17T10:58:52.977271Z","end":"2026-07-17T10:58:53.309513Z","steps":["trace[1286911619] 'process raft request' (duration: 332.094403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:53.309651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:52.977261Z","time spent":"332.313709ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:58:53.309757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.161954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-17T10:58:53.309849Z","caller":"traceutil/trace.go:171","msg":"trace[1378662481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"128.282306ms","start":"2026-07-17T10:58:53.181539Z","end":"2026-07-17T10:58:53.309821Z","steps":["trace[1378662481] 'agreement among raft nodes before linearized reading' (duration: 128.009569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:53.310022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.837667ms","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-17T10:58:53.310068Z","caller":"traceutil/trace.go:171","msg":"trace[294569695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"101.912749ms","start":"2026-07-17T10:58:53.208146Z","end":"2026-07-17T10:58:53.310059Z","steps":["trace[294569695] 'agreement among raft nodes before linearized reading' (duration: 101.397875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:53.664728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.015072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:58:53.66489Z","caller":"traceutil/trace.go:171","msg":"trace[82680276] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"350.6582ms","start":"2026-07-17T10:58:53.314219Z","end":"2026-07-17T10:58:53.664878Z","steps":["trace[82680276] 'process raft request' (duration: 350.589538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:53.664935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:53.314213Z","time spent":"350.702851ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:58:53.664999Z","caller":"traceutil/trace.go:171","msg":"trace[998445164] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"350.928587ms","start":"2026-07-17T10:58:53.31404Z","end":"2026-07-17T10:58:53.664969Z","steps":["trace[998445164] 'process raft request' (duration: 42.612147ms)","trace[998445164] 'compare' (duration: 307.909399ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:58:53.665084Z","caller":"traceutil/trace.go:171","msg":"trace[83379471] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1073; }","duration":"298.480271ms","start":"2026-07-17T10:58:53.366575Z","end":"2026-07-17T10:58:53.665055Z","steps":["trace[83379471] 'read index received' (duration: 298.032988ms)","trace[83379471] 'applied index is now lower than readState.Index' (duration: 445.943µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:58:53.665144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:53.314027Z","time spent":"351.04547ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:58:53.665195Z","caller":"traceutil/trace.go:171","msg":"trace[2007876505] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"350.693832ms","start":"2026-07-17T10:58:53.314493Z","end":"2026-07-17T10:58:53.665187Z","steps":["trace[2007876505] 'process raft request' (duration: 350.467535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:53.66523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:53.314489Z","time spent":"350.722903ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:58:53.665215Z","caller":"traceutil/trace.go:171","msg":"trace[1599471478] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"350.940879ms","start":"2026-07-17T10:58:53.314249Z","end":"2026-07-17T10:58:53.66519Z","steps":["trace[1599471478] 'process raft request' (duration: 350.600478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:53.665242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.641846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:58:53.66529Z","caller":"traceutil/trace.go:171","msg":"trace[742400976] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1034; }","duration":"298.738659ms","start":"2026-07-17T10:58:53.366535Z","end":"2026-07-17T10:58:53.665274Z","steps":["trace[742400976] 'agreement among raft nodes before linearized reading' (duration: 298.627915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:58:53.665342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:58:53.314246Z","time spent":"351.018691ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:58:59.839371Z","caller":"traceutil/trace.go:171","msg":"trace[1983679743] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"122.443023ms","start":"2026-07-17T10:58:59.716903Z","end":"2026-07-17T10:58:59.839346Z","steps":["trace[1983679743] 'process raft request' (duration: 72.421776ms)","trace[1983679743] 'compare' (duration: 49.822842ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:59:01.852613Z","caller":"traceutil/trace.go:171","msg":"trace[1056158139] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"131.355926ms","start":"2026-07-17T10:59:01.721236Z","end":"2026-07-17T10:59:01.852592Z","steps":["trace[1056158139] 'process raft request' (duration: 131.228752ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:59:01.862551Z","caller":"traceutil/trace.go:171","msg":"trace[75148545] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"120.492475ms","start":"2026-07-17T10:59:01.742032Z","end":"2026-07-17T10:59:01.862524Z","steps":["trace[75148545] 'process raft request' (duration: 120.375502ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:59:04.078005Z","caller":"traceutil/trace.go:171","msg":"trace[1907517096] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"216.536664ms","start":"2026-07-17T10:59:03.86143Z","end":"2026-07-17T10:59:04.077966Z","steps":["trace[1907517096] 'process raft request' (duration: 216.339068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.44495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.577946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T10:59:04.44509Z","caller":"traceutil/trace.go:171","msg":"trace[791280505] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"575.953452ms","start":"2026-07-17T10:59:03.869124Z","end":"2026-07-17T10:59:04.445078Z","steps":["trace[791280505] 'read index received' (duration: 208.899906ms)","trace[791280505] 'applied index is now lower than readState.Index' (duration: 367.052736ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:59:04.445122Z","caller":"traceutil/trace.go:171","msg":"trace[651939805] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"576.981981ms","start":"2026-07-17T10:59:03.868122Z","end":"2026-07-17T10:59:04.445104Z","steps":["trace[651939805] 'process raft request' (duration: 387.180548ms)","trace[651939805] 'compare' (duration: 189.496964ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:59:04.445185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:59:03.868089Z","time spent":"577.063932ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:59:04.445315Z","caller":"traceutil/trace.go:171","msg":"trace[94597879] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"491.532473ms","start":"2026-07-17T10:59:03.953776Z","end":"2026-07-17T10:59:04.445308Z","steps":["trace[94597879] 'process raft request' (duration: 491.265815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.445363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:59:03.953756Z","time spent":"491.583885ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T10:59:04.445466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.341433ms","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-17T10:59:04.445495Z","caller":"traceutil/trace.go:171","msg":"trace[1890602678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1066; }","duration":"576.400405ms","start":"2026-07-17T10:59:03.869087Z","end":"2026-07-17T10:59:04.445487Z","steps":["trace[1890602678] 'agreement among raft nodes before linearized reading' (duration: 576.23557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.445511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:59:03.869073Z","time spent":"576.435005ms","remote":"127.0.0.1:39320","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-17T10:59:04.445646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.934985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T10:59:04.445668Z","caller":"traceutil/trace.go:171","msg":"trace[1410193732] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1066; }","duration":"296.011638ms","start":"2026-07-17T10:59:04.149652Z","end":"2026-07-17T10:59:04.445663Z","steps":["trace[1410193732] 'agreement among raft nodes before linearized reading' (duration: 295.956146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.445665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.779613ms","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-17T10:59:04.44569Z","caller":"traceutil/trace.go:171","msg":"trace[1287948744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"563.853445ms","start":"2026-07-17T10:59:03.88183Z","end":"2026-07-17T10:59:04.445684Z","steps":["trace[1287948744] 'agreement among raft nodes before linearized reading' (duration: 563.782673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.445708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:59:03.88181Z","time spent":"563.893936ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T10:59:04.445745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.834304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T10:59:04.445852Z","caller":"traceutil/trace.go:171","msg":"trace[1545148349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"563.999549ms","start":"2026-07-17T10:59:03.88183Z","end":"2026-07-17T10:59:04.44583Z","steps":["trace[1545148349] 'agreement among raft nodes before linearized reading' (duration: 563.749232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.445896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:59:03.881813Z","time spent":"564.074241ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T10:59:04.698017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.595959ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-17T10:59:04.761551Z","caller":"traceutil/trace.go:171","msg":"trace[296223411] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"310.428382ms","start":"2026-07-17T10:59:04.451091Z","end":"2026-07-17T10:59:04.761519Z","steps":["trace[296223411] 'process raft request' (duration: 310.34487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.761849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:59:04.451072Z","time spent":"310.593856ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:59:04.762008Z","caller":"traceutil/trace.go:171","msg":"trace[1900384473] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"308.764314ms","start":"2026-07-17T10:59:04.453145Z","end":"2026-07-17T10:59:04.761909Z","steps":["trace[1900384473] 'process raft request' (duration: 308.333582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.762236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:59:04.453114Z","time spent":"308.959969ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:59:04.762273Z","caller":"traceutil/trace.go:171","msg":"trace[1772598789] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"312.412708ms","start":"2026-07-17T10:59:04.449843Z","end":"2026-07-17T10:59:04.762256Z","steps":["trace[1772598789] 'process raft request' (duration: 311.363478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:04.7624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T10:59:04.449824Z","time spent":"312.50048ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T10:59:04.856116Z","caller":"traceutil/trace.go:171","msg":"trace[2048338539] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1113; }","duration":"113.32614ms","start":"2026-07-17T10:59:04.742756Z","end":"2026-07-17T10:59:04.856082Z","steps":["trace[2048338539] 'read index received' (duration: 18.699417ms)","trace[2048338539] 'applied index is now lower than readState.Index' (duration: 94.625143ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:59:04.85617Z","caller":"traceutil/trace.go:171","msg":"trace[912947416] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"156.803507ms","start":"2026-07-17T10:59:04.699311Z","end":"2026-07-17T10:59:04.856115Z","steps":["trace[912947416] 'process raft request' (duration: 104.366454ms)","trace[912947416] 'compare' (duration: 52.279888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T10:59:04.856282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.517615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T10:59:04.856537Z","caller":"traceutil/trace.go:171","msg":"trace[1788480947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"113.844784ms","start":"2026-07-17T10:59:04.742678Z","end":"2026-07-17T10:59:04.856523Z","steps":["trace[1788480947] 'agreement among raft nodes before linearized reading' (duration: 113.518965ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:59:11.025654Z","caller":"traceutil/trace.go:171","msg":"trace[44886465] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"147.484454ms","start":"2026-07-17T10:59:10.878152Z","end":"2026-07-17T10:59:11.025636Z","steps":["trace[44886465] 'read index received' (duration: 147.286127ms)","trace[44886465] 'applied index is now lower than readState.Index' (duration: 197.667µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T10:59:11.025689Z","caller":"traceutil/trace.go:171","msg":"trace[1214445435] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"210.679566ms","start":"2026-07-17T10:59:10.814987Z","end":"2026-07-17T10:59:11.025666Z","steps":["trace[1214445435] 'process raft request' (duration: 210.535561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:11.025823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.651538ms","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-17T10:59:11.025854Z","caller":"traceutil/trace.go:171","msg":"trace[365459271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"147.746541ms","start":"2026-07-17T10:59:10.878098Z","end":"2026-07-17T10:59:11.025844Z","steps":["trace[365459271] 'agreement among raft nodes before linearized reading' (duration: 147.624257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T10:59:11.025959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.72668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T10:59:11.0262Z","caller":"traceutil/trace.go:171","msg":"trace[606034187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"147.993758ms","start":"2026-07-17T10:59:10.878188Z","end":"2026-07-17T10:59:11.026182Z","steps":["trace[606034187] 'agreement among raft nodes before linearized reading' (duration: 147.593127ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T10:59:18.596437Z","caller":"traceutil/trace.go:171","msg":"trace[748883736] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"104.128471ms","start":"2026-07-17T10:59:18.492285Z","end":"2026-07-17T10:59:18.596413Z","steps":["trace[748883736] 'process raft request' (duration: 103.949386ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:00:02.698474Z","caller":"traceutil/trace.go:171","msg":"trace[359179330] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"184.78697ms","start":"2026-07-17T11:00:02.513664Z","end":"2026-07-17T11:00:02.698451Z","steps":["trace[359179330] 'read index received' (duration: 184.512162ms)","trace[359179330] 'applied index is now lower than readState.Index' (duration: 273.528µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:00:02.69863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.941025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:00:02.698671Z","caller":"traceutil/trace.go:171","msg":"trace[1840654458] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1288; }","duration":"185.033537ms","start":"2026-07-17T11:00:02.513626Z","end":"2026-07-17T11:00:02.698659Z","steps":["trace[1840654458] 'agreement among raft nodes before linearized reading' (duration: 184.925274ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:00:02.698686Z","caller":"traceutil/trace.go:171","msg":"trace[1807499437] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"260.266703ms","start":"2026-07-17T11:00:02.438365Z","end":"2026-07-17T11:00:02.698632Z","steps":["trace[1807499437] 'process raft request' (duration: 259.859971ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:00:10.888713Z","caller":"traceutil/trace.go:171","msg":"trace[613755999] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"145.606598ms","start":"2026-07-17T11:00:10.743084Z","end":"2026-07-17T11:00:10.888691Z","steps":["trace[613755999] 'read index received' (duration: 145.508255ms)","trace[613755999] 'applied index is now lower than readState.Index' (duration: 97.013µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:00:10.888868Z","caller":"traceutil/trace.go:171","msg":"trace[235289577] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"151.252986ms","start":"2026-07-17T11:00:10.737552Z","end":"2026-07-17T11:00:10.888805Z","steps":["trace[235289577] 'process raft request' (duration: 150.987389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:00:10.888927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.799043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:00:10.889093Z","caller":"traceutil/trace.go:171","msg":"trace[1498059737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"146.042961ms","start":"2026-07-17T11:00:10.743033Z","end":"2026-07-17T11:00:10.889076Z","steps":["trace[1498059737] 'agreement among raft nodes before linearized reading' (duration: 145.767302ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:00:11.00413Z","caller":"traceutil/trace.go:171","msg":"trace[116992481] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"112.186991ms","start":"2026-07-17T11:00:10.891908Z","end":"2026-07-17T11:00:11.004095Z","steps":["trace[116992481] 'process raft request' (duration: 33.905789ms)","trace[116992481] 'compare' (duration: 78.027905ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:00:11.45793Z","caller":"traceutil/trace.go:171","msg":"trace[533824811] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"209.04744ms","start":"2026-07-17T11:00:11.248852Z","end":"2026-07-17T11:00:11.457899Z","steps":["trace[533824811] 'process raft request' (duration: 208.850744ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:00:12.245981Z","caller":"traceutil/trace.go:171","msg":"trace[1415004496] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"501.610171ms","start":"2026-07-17T11:00:11.744346Z","end":"2026-07-17T11:00:12.245957Z","steps":["trace[1415004496] 'process raft request' (duration: 501.449367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:00:12.246406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:00:11.744323Z","time spent":"501.728825ms","remote":"127.0.0.1:39320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}