{"level":"warn","ts":"2026-06-16T00:32:21.879411Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T00:32:21.879493Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.184:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.184:2380","--initial-cluster=instance=https://199.19.213.184:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.184:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.184:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-16T00:32:21.879555Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T00:32:21.879563Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.184:2380"]} {"level":"info","ts":"2026-06-16T00:32:21.879582Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-16T00:32:21.880294Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.184:2379"]} {"level":"info","ts":"2026-06-16T00:32:21.880387Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.184:2380"],"listen-peer-urls":["https://199.19.213.184:2380"],"advertise-client-urls":["https://199.19.213.184:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.184:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.184:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-16T00:32:21.883649Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.497601ms"} {"level":"info","ts":"2026-06-16T00:32:21.888839Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a98e0cfc7e94da4e","cluster-id":"6159be5634512a47"} {"level":"info","ts":"2026-06-16T00:32:21.888896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T00:32:21.888912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became follower at term 0"} {"level":"info","ts":"2026-06-16T00:32:21.888923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a98e0cfc7e94da4e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T00:32:21.888931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became follower at term 1"} {"level":"info","ts":"2026-06-16T00:32:21.888967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e switched to configuration voters=(12217717117697710670)"} {"level":"warn","ts":"2026-06-16T00:32:21.896897Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T00:32:21.898297Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T00:32:21.899752Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-16T00:32:21.902249Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a98e0cfc7e94da4e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T00:32:21.902979Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a98e0cfc7e94da4e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T00:32:21.904185Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:32:21.90432Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:32:21.904443Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:32:21.904757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e switched to configuration voters=(12217717117697710670)"} {"level":"info","ts":"2026-06-16T00:32:21.905008Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6159be5634512a47","local-member-id":"a98e0cfc7e94da4e","added-peer-id":"a98e0cfc7e94da4e","added-peer-peer-urls":["https://199.19.213.184:2380"]} {"level":"info","ts":"2026-06-16T00:32:21.911058Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-16T00:32:21.911131Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.184:2380"} {"level":"info","ts":"2026-06-16T00:32:21.911194Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.184:2380"} {"level":"info","ts":"2026-06-16T00:32:21.911261Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a98e0cfc7e94da4e","initial-advertise-peer-urls":["https://199.19.213.184:2380"],"listen-peer-urls":["https://199.19.213.184:2380"],"advertise-client-urls":["https://199.19.213.184:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.184:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T00:32:21.911304Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T00:32:22.389784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T00:32:22.389847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T00:32:22.389879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e received MsgPreVoteResp from a98e0cfc7e94da4e at term 1"} {"level":"info","ts":"2026-06-16T00:32:22.389897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became candidate at term 2"} {"level":"info","ts":"2026-06-16T00:32:22.389908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e received MsgVoteResp from a98e0cfc7e94da4e at term 2"} {"level":"info","ts":"2026-06-16T00:32:22.389924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a98e0cfc7e94da4e became leader at term 2"} {"level":"info","ts":"2026-06-16T00:32:22.389943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a98e0cfc7e94da4e elected leader a98e0cfc7e94da4e at term 2"} {"level":"info","ts":"2026-06-16T00:32:22.390872Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:32:22.391614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:32:22.391663Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:32:22.391966Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6159be5634512a47","local-member-id":"a98e0cfc7e94da4e","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:32:22.392058Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:32:22.392083Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:32:22.392416Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T00:32:22.392461Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T00:32:22.395152Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T00:32:22.395365Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.184:2379"} {"level":"info","ts":"2026-06-16T00:32:22.391568Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a98e0cfc7e94da4e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.184:2379]}","request-path":"/0/members/a98e0cfc7e94da4e/attributes","cluster-id":"6159be5634512a47","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T00:32:56.675638Z","caller":"traceutil/trace.go:171","msg":"trace[1678526325] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"169.848154ms","start":"2026-06-16T00:32:56.505763Z","end":"2026-06-16T00:32:56.675611Z","steps":["trace[1678526325] 'process raft request' (duration: 169.434753ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:32:56.675886Z","caller":"traceutil/trace.go:171","msg":"trace[2103295103] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"169.993167ms","start":"2026-06-16T00:32:56.505865Z","end":"2026-06-16T00:32:56.675858Z","steps":["trace[2103295103] 'process raft request' (duration: 169.598437ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:32:56.676375Z","caller":"traceutil/trace.go:171","msg":"trace[525427989] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"170.47502ms","start":"2026-06-16T00:32:56.505877Z","end":"2026-06-16T00:32:56.676352Z","steps":["trace[525427989] 'process raft request' (duration: 170.352336ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:32:56.681197Z","caller":"traceutil/trace.go:171","msg":"trace[1919799906] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"175.249532ms","start":"2026-06-16T00:32:56.50593Z","end":"2026-06-16T00:32:56.68118Z","steps":["trace[1919799906] 'process raft request' (duration: 174.986465ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:32:56.681486Z","caller":"traceutil/trace.go:171","msg":"trace[1258045751] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"133.040452ms","start":"2026-06-16T00:32:56.548427Z","end":"2026-06-16T00:32:56.681468Z","steps":["trace[1258045751] 'process raft request' (duration: 132.782046ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:32:56.68165Z","caller":"traceutil/trace.go:171","msg":"trace[975398155] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"142.629091ms","start":"2026-06-16T00:32:56.539004Z","end":"2026-06-16T00:32:56.681633Z","steps":["trace[975398155] 'process raft request' (duration: 142.155189ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:32:56.681701Z","caller":"traceutil/trace.go:171","msg":"trace[1875894541] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"156.244222ms","start":"2026-06-16T00:32:56.525423Z","end":"2026-06-16T00:32:56.681667Z","steps":["trace[1875894541] 'process raft request' (duration: 155.625796ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:32:56.681845Z","caller":"traceutil/trace.go:171","msg":"trace[1617243414] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"123.516687ms","start":"2026-06-16T00:32:56.558319Z","end":"2026-06-16T00:32:56.681836Z","steps":["trace[1617243414] 'process raft request' (duration: 123.104366ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:32:56.681997Z","caller":"traceutil/trace.go:171","msg":"trace[1016859417] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"143.039141ms","start":"2026-06-16T00:32:56.538943Z","end":"2026-06-16T00:32:56.681982Z","steps":["trace[1016859417] 'process raft request' (duration: 142.151288ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:03.250164Z","caller":"traceutil/trace.go:171","msg":"trace[1205304166] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"164.329981ms","start":"2026-06-16T00:33:03.085812Z","end":"2026-06-16T00:33:03.250142Z","steps":["trace[1205304166] 'read index received' (duration: 163.566042ms)","trace[1205304166] 'applied index is now lower than readState.Index' (duration: 762.679µs)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:33:03.250299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.460294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sp8nm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-16T00:33:03.250324Z","caller":"traceutil/trace.go:171","msg":"trace[1498855872] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"346.250206ms","start":"2026-06-16T00:33:02.903963Z","end":"2026-06-16T00:33:03.250213Z","steps":["trace[1498855872] 'process raft request' (duration: 345.643461ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:03.250355Z","caller":"traceutil/trace.go:171","msg":"trace[1947737507] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sp8nm; range_end:; response_count:1; response_revision:586; }","duration":"164.583027ms","start":"2026-06-16T00:33:03.085765Z","end":"2026-06-16T00:33:03.250348Z","steps":["trace[1947737507] 'agreement among raft nodes before linearized reading' (duration: 164.467275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:03.250966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:02.903938Z","time spent":"346.497923ms","remote":"127.0.0.1:40300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:33:03.518526Z","caller":"traceutil/trace.go:171","msg":"trace[1297183551] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"246.222315ms","start":"2026-06-16T00:33:03.272275Z","end":"2026-06-16T00:33:03.518498Z","steps":["trace[1297183551] 'process raft request' (duration: 246.077292ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:03.5665Z","caller":"traceutil/trace.go:171","msg":"trace[280918713] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"259.57811ms","start":"2026-06-16T00:33:03.306879Z","end":"2026-06-16T00:33:03.566457Z","steps":["trace[280918713] 'process raft request' (duration: 259.412816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:04.429197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.330628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:33:04.429301Z","caller":"traceutil/trace.go:171","msg":"trace[1098891155] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"856.316188ms","start":"2026-06-16T00:33:03.572969Z","end":"2026-06-16T00:33:04.429285Z","steps":["trace[1098891155] 'read index received' (duration: 409.272321ms)","trace[1098891155] 'applied index is now lower than readState.Index' (duration: 447.042167ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:33:04.429384Z","caller":"traceutil/trace.go:171","msg":"trace[791572244] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"859.428959ms","start":"2026-06-16T00:33:03.569945Z","end":"2026-06-16T00:33:04.429374Z","steps":["trace[791572244] 'process raft request' (duration: 412.389322ms)","trace[791572244] 'compare' (duration: 446.04401ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:33:04.429446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:03.569924Z","time spent":"859.491361ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:33:04.429671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.697028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-16T00:33:04.429708Z","caller":"traceutil/trace.go:171","msg":"trace[889057890] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:589; }","duration":"856.75893ms","start":"2026-06-16T00:33:03.57294Z","end":"2026-06-16T00:33:04.429699Z","steps":["trace[889057890] 'agreement among raft nodes before linearized reading' (duration: 856.679658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:04.429792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:03.572928Z","time spent":"856.856312ms","remote":"127.0.0.1:40394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-06-16T00:33:04.429891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.635335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T00:33:04.42996Z","caller":"traceutil/trace.go:171","msg":"trace[431403442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"127.742098ms","start":"2026-06-16T00:33:04.3022Z","end":"2026-06-16T00:33:04.429942Z","steps":["trace[431403442] 'agreement among raft nodes before linearized reading' (duration: 127.562643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:04.430087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.696832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-16T00:33:04.429855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.983642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:33:04.430145Z","caller":"traceutil/trace.go:171","msg":"trace[800335760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"586.362572ms","start":"2026-06-16T00:33:03.843773Z","end":"2026-06-16T00:33:04.430136Z","steps":["trace[800335760] 'agreement among raft nodes before linearized reading' (duration: 585.920621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:04.430177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:03.843757Z","time spent":"586.410373ms","remote":"127.0.0.1:40194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T00:33:04.430176Z","caller":"traceutil/trace.go:171","msg":"trace[1238485499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"438.819815ms","start":"2026-06-16T00:33:03.991304Z","end":"2026-06-16T00:33:04.430124Z","steps":["trace[1238485499] 'agreement among raft nodes before linearized reading' (duration: 438.62945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:04.430265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:03.991284Z","time spent":"438.930598ms","remote":"127.0.0.1:40464","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-06-16T00:33:04.60371Z","caller":"traceutil/trace.go:171","msg":"trace[1289622168] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"165.404089ms","start":"2026-06-16T00:33:04.43828Z","end":"2026-06-16T00:33:04.603684Z","steps":["trace[1289622168] 'read index received' (duration: 78.168038ms)","trace[1289622168] 'applied index is now lower than readState.Index' (duration: 87.234951ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:33:04.603823Z","caller":"traceutil/trace.go:171","msg":"trace[1226443484] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"168.382456ms","start":"2026-06-16T00:33:04.435405Z","end":"2026-06-16T00:33:04.603788Z","steps":["trace[1226443484] 'process raft request' (duration: 93.787071ms)","trace[1226443484] 'compare' (duration: 74.37755ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:33:04.603909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.611764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:33:04.604024Z","caller":"traceutil/trace.go:171","msg":"trace[1963374930] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:591; }","duration":"165.779779ms","start":"2026-06-16T00:33:04.438223Z","end":"2026-06-16T00:33:04.604003Z","steps":["trace[1963374930] 'agreement among raft nodes before linearized reading' (duration: 165.595374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:04.604104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.029399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T00:33:04.604151Z","caller":"traceutil/trace.go:171","msg":"trace[684788801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"115.126001ms","start":"2026-06-16T00:33:04.489014Z","end":"2026-06-16T00:33:04.60414Z","steps":["trace[684788801] 'agreement among raft nodes before linearized reading' (duration: 114.845014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:05.7636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15730685155380135463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T00:33:05.960016Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.352907814s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T00:33:05.960396Z","caller":"traceutil/trace.go:171","msg":"trace[778851632] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.353408047s","start":"2026-06-16T00:33:04.606966Z","end":"2026-06-16T00:33:05.960374Z","steps":["trace[778851632] 'process raft request' (duration: 1.353281884s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:05.960524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:04.606936Z","time spent":"1.35353379s","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:33:06.147326Z","caller":"traceutil/trace.go:171","msg":"trace[1115752952] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.538780281s","start":"2026-06-16T00:33:04.608515Z","end":"2026-06-16T00:33:06.147295Z","steps":["trace[1115752952] 'process raft request' (duration: 1.501550451s)","trace[1115752952] 'compare' (duration: 36.807729ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:33:06.147608Z","caller":"traceutil/trace.go:171","msg":"trace[1711195401] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:608; }","duration":"884.403943ms","start":"2026-06-16T00:33:05.263174Z","end":"2026-06-16T00:33:06.147578Z","steps":["trace[1711195401] 'read index received' (duration: 697.058488ms)","trace[1711195401] 'applied index is now lower than readState.Index' (duration: 187.343755ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:33:06.147686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:04.608491Z","time spent":"1.538921774s","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:33:06.147678Z","caller":"traceutil/trace.go:171","msg":"trace[702896007] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.432562509s","start":"2026-06-16T00:33:04.715083Z","end":"2026-06-16T00:33:06.147646Z","steps":["trace[702896007] 'process raft request' (duration: 1.432111997s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:06.147827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.638859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.184\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-16T00:33:06.147879Z","caller":"traceutil/trace.go:171","msg":"trace[505514805] range","detail":"{range_begin:/registry/masterleases/199.19.213.184; range_end:; response_count:1; response_revision:594; }","duration":"884.740531ms","start":"2026-06-16T00:33:05.263124Z","end":"2026-06-16T00:33:06.147864Z","steps":["trace[505514805] 'agreement among raft nodes before linearized reading' (duration: 884.547716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:06.147922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:05.263103Z","time spent":"884.810414ms","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.184\" "} {"level":"warn","ts":"2026-06-16T00:33:06.147962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:04.715055Z","time spent":"1.432811015s","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:33:06.169749Z","caller":"traceutil/trace.go:171","msg":"trace[502279007] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"205.526084ms","start":"2026-06-16T00:33:05.964195Z","end":"2026-06-16T00:33:06.169721Z","steps":["trace[502279007] 'process raft request' (duration: 205.313968ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:06.170051Z","caller":"traceutil/trace.go:171","msg":"trace[1460252775] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"203.133772ms","start":"2026-06-16T00:33:05.966906Z","end":"2026-06-16T00:33:06.17004Z","steps":["trace[1460252775] 'process raft request' (duration: 202.735362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:06.169833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.719041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:33:06.170135Z","caller":"traceutil/trace.go:171","msg":"trace[839326790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"327.078181ms","start":"2026-06-16T00:33:05.843041Z","end":"2026-06-16T00:33:06.17012Z","steps":["trace[839326790] 'agreement among raft nodes before linearized reading' (duration: 326.709571ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:06.170152Z","caller":"traceutil/trace.go:171","msg":"trace[1562331425] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"203.007028ms","start":"2026-06-16T00:33:05.967127Z","end":"2026-06-16T00:33:06.170134Z","steps":["trace[1562331425] 'process raft request' (duration: 202.582567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:06.170198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:05.843023Z","time spent":"327.155362ms","remote":"127.0.0.1:40194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T00:33:06.169885Z","caller":"traceutil/trace.go:171","msg":"trace[1544902406] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"204.334073ms","start":"2026-06-16T00:33:05.965537Z","end":"2026-06-16T00:33:06.169872Z","steps":["trace[1544902406] 'process raft request' (duration: 204.062757ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:22.280799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.253279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:33:22.280879Z","caller":"traceutil/trace.go:171","msg":"trace[733954488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"436.344001ms","start":"2026-06-16T00:33:21.844517Z","end":"2026-06-16T00:33:22.280861Z","steps":["trace[733954488] 'range keys from in-memory index tree' (duration: 436.157686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:22.280914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:21.84447Z","time spent":"436.435843ms","remote":"127.0.0.1:40194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T00:33:30.337848Z","caller":"traceutil/trace.go:171","msg":"trace[1129099824] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"103.760838ms","start":"2026-06-16T00:33:30.234054Z","end":"2026-06-16T00:33:30.337815Z","steps":["trace[1129099824] 'process raft request' (duration: 70.665404ms)","trace[1129099824] 'compare' (duration: 32.669713ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:33:30.370726Z","caller":"traceutil/trace.go:171","msg":"trace[797426566] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"130.56917ms","start":"2026-06-16T00:33:30.240137Z","end":"2026-06-16T00:33:30.370707Z","steps":["trace[797426566] 'process raft request' (duration: 130.391455ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:56.760787Z","caller":"traceutil/trace.go:171","msg":"trace[1018299279] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"139.467328ms","start":"2026-06-16T00:33:56.621291Z","end":"2026-06-16T00:33:56.760759Z","steps":["trace[1018299279] 'process raft request' (duration: 139.296844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:57.339846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.949358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:33:57.339955Z","caller":"traceutil/trace.go:171","msg":"trace[1914670346] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"463.021652ms","start":"2026-06-16T00:33:56.876916Z","end":"2026-06-16T00:33:57.339938Z","steps":["trace[1914670346] 'read index received' (duration: 167.91317ms)","trace[1914670346] 'applied index is now lower than readState.Index' (duration: 295.107292ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:33:57.340018Z","caller":"traceutil/trace.go:171","msg":"trace[1367853916] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"575.567897ms","start":"2026-06-16T00:33:56.764421Z","end":"2026-06-16T00:33:57.339989Z","steps":["trace[1367853916] 'process raft request' (duration: 280.399434ms)","trace[1367853916] 'compare' (duration: 294.826585ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:33:57.340137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.218677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-16T00:33:57.340164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:56.764398Z","time spent":"575.69486ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:33:57.340174Z","caller":"traceutil/trace.go:171","msg":"trace[1333697856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:972; }","duration":"463.280648ms","start":"2026-06-16T00:33:56.876884Z","end":"2026-06-16T00:33:57.340165Z","steps":["trace[1333697856] 'agreement among raft nodes before linearized reading' (duration: 463.141385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:57.340217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:56.876822Z","time spent":"463.387761ms","remote":"127.0.0.1:40464","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-06-16T00:33:57.34038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.885688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T00:33:57.340406Z","caller":"traceutil/trace.go:171","msg":"trace[1707491422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"408.912548ms","start":"2026-06-16T00:33:56.931488Z","end":"2026-06-16T00:33:57.3404Z","steps":["trace[1707491422] 'agreement among raft nodes before linearized reading' (duration: 408.838206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:57.340424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:56.931463Z","time spent":"408.95621ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T00:33:57.718051Z","caller":"traceutil/trace.go:171","msg":"trace[1909358605] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"280.976158ms","start":"2026-06-16T00:33:57.437056Z","end":"2026-06-16T00:33:57.718032Z","steps":["trace[1909358605] 'process raft request' (duration: 280.907527ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:57.71818Z","caller":"traceutil/trace.go:171","msg":"trace[1704041092] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"373.425611ms","start":"2026-06-16T00:33:57.344746Z","end":"2026-06-16T00:33:57.718171Z","steps":["trace[1704041092] 'process raft request' (duration: 373.140924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:33:57.718245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:57.344735Z","time spent":"373.459071ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:33:57.718265Z","caller":"traceutil/trace.go:171","msg":"trace[1001207929] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"271.179374ms","start":"2026-06-16T00:33:57.447065Z","end":"2026-06-16T00:33:57.718244Z","steps":["trace[1001207929] 'process raft request' (duration: 270.922287ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:33:57.718541Z","caller":"traceutil/trace.go:171","msg":"trace[218264692] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"374.501737ms","start":"2026-06-16T00:33:57.343946Z","end":"2026-06-16T00:33:57.718448Z","steps":["trace[218264692] 'process raft request' (duration: 355.014969ms)","trace[218264692] 'compare' (duration: 18.581015ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:33:57.718686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:57.34391Z","time spent":"374.705882ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:17.690207Z","caller":"traceutil/trace.go:171","msg":"trace[1258531773] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"244.371474ms","start":"2026-06-16T00:34:17.445811Z","end":"2026-06-16T00:34:17.690183Z","steps":["trace[1258531773] 'process raft request' (duration: 244.24601ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:18.035294Z","caller":"traceutil/trace.go:171","msg":"trace[1325177321] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"217.516861ms","start":"2026-06-16T00:34:17.817753Z","end":"2026-06-16T00:34:18.03527Z","steps":["trace[1325177321] 'read index received' (duration: 149.600771ms)","trace[1325177321] 'applied index is now lower than readState.Index' (duration: 67.91502ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:18.035349Z","caller":"traceutil/trace.go:171","msg":"trace[661001730] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"239.521062ms","start":"2026-06-16T00:34:17.795804Z","end":"2026-06-16T00:34:18.035325Z","steps":["trace[661001730] 'process raft request' (duration: 171.574272ms)","trace[661001730] 'compare' (duration: 67.566721ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:18.035551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.372106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:34:18.035636Z","caller":"traceutil/trace.go:171","msg":"trace[1289506532] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1039; }","duration":"115.507978ms","start":"2026-06-16T00:34:17.920116Z","end":"2026-06-16T00:34:18.035624Z","steps":["trace[1289506532] 'agreement among raft nodes before linearized reading' (duration: 115.367495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:18.035629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.761971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-16T00:34:18.03564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.423878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T00:34:18.035686Z","caller":"traceutil/trace.go:171","msg":"trace[1649085867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"116.850043ms","start":"2026-06-16T00:34:17.918822Z","end":"2026-06-16T00:34:18.035672Z","steps":["trace[1649085867] 'agreement among raft nodes before linearized reading' (duration: 116.658868ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:18.035733Z","caller":"traceutil/trace.go:171","msg":"trace[1909424388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"213.547991ms","start":"2026-06-16T00:34:17.822166Z","end":"2026-06-16T00:34:18.035714Z","steps":["trace[1909424388] 'agreement among raft nodes before linearized reading' (duration: 213.283834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:18.035647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.169041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:18.035865Z","caller":"traceutil/trace.go:171","msg":"trace[344568428] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1039; }","duration":"125.415987ms","start":"2026-06-16T00:34:17.910433Z","end":"2026-06-16T00:34:18.035849Z","steps":["trace[344568428] 'agreement among raft nodes before linearized reading' (duration: 125.14437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:18.036093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.333422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T00:34:18.036145Z","caller":"traceutil/trace.go:171","msg":"trace[1464781805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"218.421283ms","start":"2026-06-16T00:34:17.817711Z","end":"2026-06-16T00:34:18.036132Z","steps":["trace[1464781805] 'agreement among raft nodes before linearized reading' (duration: 217.683715ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:18.988417Z","caller":"traceutil/trace.go:171","msg":"trace[1617456230] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"124.947436ms","start":"2026-06-16T00:34:18.863452Z","end":"2026-06-16T00:34:18.9884Z","steps":["trace[1617456230] 'process raft request' (duration: 124.816093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:19.275494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.402466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:19.275587Z","caller":"traceutil/trace.go:171","msg":"trace[287423236] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"144.978046ms","start":"2026-06-16T00:34:19.130588Z","end":"2026-06-16T00:34:19.275566Z","steps":["trace[287423236] 'read index received' (duration: 37.408725ms)","trace[287423236] 'applied index is now lower than readState.Index' (duration: 107.568231ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:19.275643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.057267ms","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:7"} {"level":"info","ts":"2026-06-16T00:34:19.27562Z","caller":"traceutil/trace.go:171","msg":"trace[828766061] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"231.414748ms","start":"2026-06-16T00:34:19.044173Z","end":"2026-06-16T00:34:19.275587Z","steps":["trace[828766061] 'process raft request' (duration: 123.848148ms)","trace[828766061] 'compare' (duration: 107.305534ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:19.275667Z","caller":"traceutil/trace.go:171","msg":"trace[1992632622] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1045; }","duration":"145.120269ms","start":"2026-06-16T00:34:19.130541Z","end":"2026-06-16T00:34:19.275661Z","steps":["trace[1992632622] 'agreement among raft nodes before linearized reading' (duration: 145.070978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:19.668369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.467794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:34:19.668441Z","caller":"traceutil/trace.go:171","msg":"trace[43106284] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1045; }","duration":"116.604817ms","start":"2026-06-16T00:34:19.551816Z","end":"2026-06-16T00:34:19.668421Z","steps":["trace[43106284] 'count revisions from in-memory index tree' (duration: 116.356701ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:21.918184Z","caller":"traceutil/trace.go:171","msg":"trace[1199675901] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"142.103105ms","start":"2026-06-16T00:34:21.776051Z","end":"2026-06-16T00:34:21.918154Z","steps":["trace[1199675901] 'process raft request' (duration: 141.845668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:22.956138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.603575ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:22.956256Z","caller":"traceutil/trace.go:171","msg":"trace[263567172] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1053; }","duration":"122.741969ms","start":"2026-06-16T00:34:22.833496Z","end":"2026-06-16T00:34:22.956238Z","steps":["trace[263567172] 'range keys from in-memory index tree' (duration: 122.576305ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:22.956704Z","caller":"traceutil/trace.go:171","msg":"trace[1389422006] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"850.302959ms","start":"2026-06-16T00:34:22.10637Z","end":"2026-06-16T00:34:22.956673Z","steps":["trace[1389422006] 'process raft request' (duration: 796.451072ms)","trace[1389422006] 'compare' (duration: 53.627741ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:22.956888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:22.106352Z","time spent":"850.412233ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:23.050212Z","caller":"traceutil/trace.go:171","msg":"trace[1034916384] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"943.028279ms","start":"2026-06-16T00:34:22.107151Z","end":"2026-06-16T00:34:23.050179Z","steps":["trace[1034916384] 'process raft request' (duration: 942.770933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:23.050395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:22.107135Z","time spent":"943.164052ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:23.050243Z","caller":"traceutil/trace.go:171","msg":"trace[1434516511] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"942.735982ms","start":"2026-06-16T00:34:22.107474Z","end":"2026-06-16T00:34:23.05021Z","steps":["trace[1434516511] 'process raft request' (duration: 942.609399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:23.050652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:22.107468Z","time spent":"943.095931ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:37.2518Z","caller":"traceutil/trace.go:171","msg":"trace[817989414] linearizableReadLoop","detail":"{readStateIndex:1193; appliedIndex:1192; }","duration":"142.385321ms","start":"2026-06-16T00:34:37.109384Z","end":"2026-06-16T00:34:37.251769Z","steps":["trace[817989414] 'read index received' (duration: 142.170056ms)","trace[817989414] 'applied index is now lower than readState.Index' (duration: 213.875µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:37.251921Z","caller":"traceutil/trace.go:171","msg":"trace[202243925] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"214.27609ms","start":"2026-06-16T00:34:37.037604Z","end":"2026-06-16T00:34:37.25188Z","steps":["trace[202243925] 'process raft request' (duration: 214.021154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.252147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.778141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-16T00:34:37.252191Z","caller":"traceutil/trace.go:171","msg":"trace[1947186136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1151; }","duration":"140.864823ms","start":"2026-06-16T00:34:37.111314Z","end":"2026-06-16T00:34:37.252179Z","steps":["trace[1947186136] 'agreement among raft nodes before linearized reading' (duration: 140.73051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:37.252332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.948686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-16T00:34:37.252418Z","caller":"traceutil/trace.go:171","msg":"trace[1317662663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1151; }","duration":"143.060259ms","start":"2026-06-16T00:34:37.109348Z","end":"2026-06-16T00:34:37.252408Z","steps":["trace[1317662663] 'agreement among raft nodes before linearized reading' (duration: 142.612267ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:37.396252Z","caller":"traceutil/trace.go:171","msg":"trace[1218754919] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"139.55003ms","start":"2026-06-16T00:34:37.25645Z","end":"2026-06-16T00:34:37.396Z","steps":["trace[1218754919] 'process raft request' (duration: 139.388276ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:37.411706Z","caller":"traceutil/trace.go:171","msg":"trace[242779918] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"154.34842ms","start":"2026-06-16T00:34:37.257342Z","end":"2026-06-16T00:34:37.41169Z","steps":["trace[242779918] 'process raft request' (duration: 154.32096ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:37.411975Z","caller":"traceutil/trace.go:171","msg":"trace[2111112484] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"155.131271ms","start":"2026-06-16T00:34:37.256839Z","end":"2026-06-16T00:34:37.41197Z","steps":["trace[2111112484] 'process raft request' (duration: 154.70095ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:38.469317Z","caller":"traceutil/trace.go:171","msg":"trace[596151315] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"483.378532ms","start":"2026-06-16T00:34:37.985914Z","end":"2026-06-16T00:34:38.469293Z","steps":["trace[596151315] 'process raft request' (duration: 483.097305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:38.46946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:37.985903Z","time spent":"483.477814ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:38.617893Z","caller":"traceutil/trace.go:171","msg":"trace[181799627] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"461.818542ms","start":"2026-06-16T00:34:38.156058Z","end":"2026-06-16T00:34:38.617877Z","steps":["trace[181799627] 'process raft request' (duration: 459.468733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:38.618004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:38.156034Z","time spent":"461.919914ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:38.627812Z","caller":"traceutil/trace.go:171","msg":"trace[1440571658] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"114.005852ms","start":"2026-06-16T00:34:38.513796Z","end":"2026-06-16T00:34:38.627802Z","steps":["trace[1440571658] 'process raft request' (duration: 113.93855ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:51.474874Z","caller":"traceutil/trace.go:171","msg":"trace[793421867] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"160.927015ms","start":"2026-06-16T00:34:51.31392Z","end":"2026-06-16T00:34:51.474847Z","steps":["trace[793421867] 'process raft request' (duration: 160.616277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:56.324359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.931957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:56.324852Z","caller":"traceutil/trace.go:171","msg":"trace[4611114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"483.415309ms","start":"2026-06-16T00:34:55.841353Z","end":"2026-06-16T00:34:56.324768Z","steps":["trace[4611114] 'range keys from in-memory index tree' (duration: 482.853315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:56.32538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:55.841333Z","time spent":"484.030613ms","remote":"127.0.0.1:40194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T00:34:56.793669Z","caller":"traceutil/trace.go:171","msg":"trace[1227886195] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"129.195258ms","start":"2026-06-16T00:34:56.664447Z","end":"2026-06-16T00:34:56.793642Z","steps":["trace[1227886195] 'read index received' (duration: 129.084965ms)","trace[1227886195] 'applied index is now lower than readState.Index' (duration: 109.053µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:56.793788Z","caller":"traceutil/trace.go:171","msg":"trace[948065994] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"228.978246ms","start":"2026-06-16T00:34:56.564799Z","end":"2026-06-16T00:34:56.793777Z","steps":["trace[948065994] 'process raft request' (duration: 228.682079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:56.793904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.444084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:34:56.794026Z","caller":"traceutil/trace.go:171","msg":"trace[25670173] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1235; }","duration":"129.604318ms","start":"2026-06-16T00:34:56.664403Z","end":"2026-06-16T00:34:56.794007Z","steps":["trace[25670173] 'agreement among raft nodes before linearized reading' (duration: 129.440364ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:57.704774Z","caller":"traceutil/trace.go:171","msg":"trace[623165020] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"164.990417ms","start":"2026-06-16T00:34:57.539768Z","end":"2026-06-16T00:34:57.704758Z","steps":["trace[623165020] 'read index received' (duration: 164.846973ms)","trace[623165020] 'applied index is now lower than readState.Index' (duration: 142.964µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:57.705027Z","caller":"traceutil/trace.go:171","msg":"trace[316917422] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"186.471849ms","start":"2026-06-16T00:34:57.518548Z","end":"2026-06-16T00:34:57.70502Z","steps":["trace[316917422] 'process raft request' (duration: 186.116361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:57.70519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.425319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-16T00:34:57.705213Z","caller":"traceutil/trace.go:171","msg":"trace[197999924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"165.502951ms","start":"2026-06-16T00:34:57.539705Z","end":"2026-06-16T00:34:57.705208Z","steps":["trace[197999924] 'agreement among raft nodes before linearized reading' (duration: 165.420849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:57.705305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.709352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-16T00:34:57.705372Z","caller":"traceutil/trace.go:171","msg":"trace[1772300905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"156.816315ms","start":"2026-06-16T00:34:57.548541Z","end":"2026-06-16T00:34:57.705358Z","steps":["trace[1772300905] 'agreement among raft nodes before linearized reading' (duration: 156.6421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:57.705441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.39136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-16T00:34:57.705463Z","caller":"traceutil/trace.go:171","msg":"trace[856533335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"153.476431ms","start":"2026-06-16T00:34:57.551982Z","end":"2026-06-16T00:34:57.705458Z","steps":["trace[856533335] 'agreement among raft nodes before linearized reading' (duration: 153.42306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:58.401012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.260869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:58.401126Z","caller":"traceutil/trace.go:171","msg":"trace[227127311] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"690.756974ms","start":"2026-06-16T00:34:57.710345Z","end":"2026-06-16T00:34:58.401102Z","steps":["trace[227127311] 'process raft request' (duration: 549.3137ms)","trace[227127311] 'compare' (duration: 141.072764ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:58.402496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:57.710321Z","time spent":"692.121997ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:34:58.718822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.522417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:58.719042Z","caller":"traceutil/trace.go:171","msg":"trace[828193775] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"1.007733754s","start":"2026-06-16T00:34:57.711291Z","end":"2026-06-16T00:34:58.719025Z","steps":["trace[828193775] 'process raft request' (duration: 823.92356ms)","trace[828193775] 'compare' (duration: 183.415974ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:58.719095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:57.71128Z","time spent":"1.007788465s","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:58.719084Z","caller":"traceutil/trace.go:171","msg":"trace[1990737115] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"1.006785351s","start":"2026-06-16T00:34:57.712274Z","end":"2026-06-16T00:34:58.71906Z","steps":["trace[1990737115] 'process raft request' (duration: 1.006622597s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:58.719208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:57.712266Z","time spent":"1.006876303s","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:58.719317Z","caller":"traceutil/trace.go:171","msg":"trace[1575623087] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1284; }","duration":"878.161488ms","start":"2026-06-16T00:34:57.841147Z","end":"2026-06-16T00:34:58.719308Z","steps":["trace[1575623087] 'read index received' (duration: 418.524613ms)","trace[1575623087] 'applied index is now lower than readState.Index' (duration: 459.635955ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:58.719492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.654101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:58.719513Z","caller":"traceutil/trace.go:171","msg":"trace[1785411504] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1241; }","duration":"572.711533ms","start":"2026-06-16T00:34:58.146794Z","end":"2026-06-16T00:34:58.719506Z","steps":["trace[1785411504] 'agreement among raft nodes before linearized reading' (duration: 572.675942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:58.719533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:58.146774Z","time spent":"572.752783ms","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-06-16T00:34:58.719744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.640381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:58.719823Z","caller":"traceutil/trace.go:171","msg":"trace[780504765] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1241; }","duration":"216.769094ms","start":"2026-06-16T00:34:58.50303Z","end":"2026-06-16T00:34:58.719799Z","steps":["trace[780504765] 'agreement among raft nodes before linearized reading' (duration: 216.647181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:58.719452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.309371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:58.719863Z","caller":"traceutil/trace.go:171","msg":"trace[249103407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"878.748792ms","start":"2026-06-16T00:34:57.841108Z","end":"2026-06-16T00:34:58.719857Z","steps":["trace[249103407] 'agreement among raft nodes before linearized reading' (duration: 878.25671ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:58.719881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:57.841091Z","time spent":"878.783633ms","remote":"127.0.0.1:40196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-16T00:34:59.220817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.590414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:59.220954Z","caller":"traceutil/trace.go:171","msg":"trace[1857649106] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1287; }","duration":"377.558556ms","start":"2026-06-16T00:34:58.843385Z","end":"2026-06-16T00:34:59.220944Z","steps":["trace[1857649106] 'read index received' (duration: 72.788487ms)","trace[1857649106] 'applied index is now lower than readState.Index' (duration: 304.769259ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:59.221009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.624188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:59.221026Z","caller":"traceutil/trace.go:171","msg":"trace[168897629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"377.669369ms","start":"2026-06-16T00:34:58.843352Z","end":"2026-06-16T00:34:59.221021Z","steps":["trace[168897629] 'agreement among raft nodes before linearized reading' (duration: 377.621987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:59.221043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:58.843335Z","time spent":"377.70418ms","remote":"127.0.0.1:40194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T00:34:59.221168Z","caller":"traceutil/trace.go:171","msg":"trace[818075589] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"411.306194ms","start":"2026-06-16T00:34:58.809854Z","end":"2026-06-16T00:34:59.221161Z","steps":["trace[818075589] 'process raft request' (duration: 411.026637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:59.221205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:58.809836Z","time spent":"411.350825ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:59.221132Z","caller":"traceutil/trace.go:171","msg":"trace[1737088348] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"420.112493ms","start":"2026-06-16T00:34:58.800916Z","end":"2026-06-16T00:34:59.221029Z","steps":["trace[1737088348] 'process raft request' (duration: 115.270373ms)","trace[1737088348] 'compare' (duration: 304.296255ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:59.221316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:58.8009Z","time spent":"420.400079ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:34:59.243264Z","caller":"traceutil/trace.go:171","msg":"trace[1121397002] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"214.868586ms","start":"2026-06-16T00:34:59.028383Z","end":"2026-06-16T00:34:59.243251Z","steps":["trace[1121397002] 'process raft request' (duration: 214.804264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:59.813878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.569164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:59.813938Z","caller":"traceutil/trace.go:171","msg":"trace[81855640] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"592.145674ms","start":"2026-06-16T00:34:59.221783Z","end":"2026-06-16T00:34:59.813929Z","steps":["trace[81855640] 'read index received' (duration: 21.464523ms)","trace[81855640] 'applied index is now lower than readState.Index' (duration: 570.680071ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:59.814006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.218766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:34:59.813991Z","caller":"traceutil/trace.go:171","msg":"trace[169200227] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"665.658179ms","start":"2026-06-16T00:34:59.148303Z","end":"2026-06-16T00:34:59.813961Z","steps":["trace[169200227] 'process raft request' (duration: 362.964402ms)","trace[169200227] 'compare' (duration: 302.319828ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:34:59.814026Z","caller":"traceutil/trace.go:171","msg":"trace[65011710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"592.257216ms","start":"2026-06-16T00:34:59.221763Z","end":"2026-06-16T00:34:59.814021Z","steps":["trace[65011710] 'agreement among raft nodes before linearized reading' (duration: 592.209715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:59.814069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.909961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:34:59.814106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:59.14828Z","time spent":"665.768542ms","remote":"127.0.0.1:40272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:34:59.814126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:59.221754Z","time spent":"592.365999ms","remote":"127.0.0.1:40196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T00:34:59.814141Z","caller":"traceutil/trace.go:171","msg":"trace[215881117] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1245; }","duration":"576.019313ms","start":"2026-06-16T00:34:59.238105Z","end":"2026-06-16T00:34:59.814124Z","steps":["trace[215881117] 'agreement among raft nodes before linearized reading' (duration: 575.88837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:59.8142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:59.238093Z","time spent":"576.093905ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-16T00:34:59.814201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.641382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-16T00:34:59.814281Z","caller":"traceutil/trace.go:171","msg":"trace[1018265657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1245; }","duration":"105.759515ms","start":"2026-06-16T00:34:59.708496Z","end":"2026-06-16T00:34:59.814255Z","steps":["trace[1018265657] 'agreement among raft nodes before linearized reading' (duration: 105.54717ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:00.065231Z","caller":"traceutil/trace.go:171","msg":"trace[1525312443] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"247.6398ms","start":"2026-06-16T00:34:59.817566Z","end":"2026-06-16T00:35:00.065206Z","steps":["trace[1525312443] 'process raft request' (duration: 247.443375ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:00.074172Z","caller":"traceutil/trace.go:171","msg":"trace[385326277] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"256.257833ms","start":"2026-06-16T00:34:59.817888Z","end":"2026-06-16T00:35:00.074146Z","steps":["trace[385326277] 'process raft request' (duration: 256.032308ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:00.074188Z","caller":"traceutil/trace.go:171","msg":"trace[1550060450] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1291; }","duration":"200.885809ms","start":"2026-06-16T00:34:59.873281Z","end":"2026-06-16T00:35:00.074166Z","steps":["trace[1550060450] 'read index received' (duration: 191.760032ms)","trace[1550060450] 'applied index is now lower than readState.Index' (duration: 9.123267ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:35:00.074303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.009101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:35:00.074342Z","caller":"traceutil/trace.go:171","msg":"trace[1078852467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"201.062923ms","start":"2026-06-16T00:34:59.87327Z","end":"2026-06-16T00:35:00.074333Z","steps":["trace[1078852467] 'agreement among raft nodes before linearized reading' (duration: 200.95899ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:00.074683Z","caller":"traceutil/trace.go:171","msg":"trace[1056322727] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"256.740865ms","start":"2026-06-16T00:34:59.817928Z","end":"2026-06-16T00:35:00.074668Z","steps":["trace[1056322727] 'process raft request' (duration: 256.12126ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:07.180061Z","caller":"traceutil/trace.go:171","msg":"trace[2119517554] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"131.610348ms","start":"2026-06-16T00:35:07.048427Z","end":"2026-06-16T00:35:07.180037Z","steps":["trace[2119517554] 'process raft request' (duration: 131.464035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:35:07.758724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.363098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:35:07.758792Z","caller":"traceutil/trace.go:171","msg":"trace[1252847462] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"504.647921ms","start":"2026-06-16T00:35:07.254132Z","end":"2026-06-16T00:35:07.75878Z","steps":["trace[1252847462] 'read index received' (duration: 318.13196ms)","trace[1252847462] 'applied index is now lower than readState.Index' (duration: 186.515191ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:35:07.758868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.737193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T00:35:07.758881Z","caller":"traceutil/trace.go:171","msg":"trace[532514655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"504.786015ms","start":"2026-06-16T00:35:07.25409Z","end":"2026-06-16T00:35:07.758876Z","steps":["trace[532514655] 'agreement among raft nodes before linearized reading' (duration: 504.716653ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:07.75884Z","caller":"traceutil/trace.go:171","msg":"trace[514431717] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"508.072267ms","start":"2026-06-16T00:35:07.250719Z","end":"2026-06-16T00:35:07.758791Z","steps":["trace[514431717] 'process raft request' (duration: 321.592537ms)","trace[514431717] 'compare' (duration: 186.292765ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:35:07.758898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:35:07.25407Z","time spent":"504.824386ms","remote":"127.0.0.1:40464","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-06-16T00:35:07.759017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:35:07.250698Z","time spent":"508.22478ms","remote":"127.0.0.1:40464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-16T00:35:07.959427Z","caller":"traceutil/trace.go:171","msg":"trace[1127118443] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"197.178326ms","start":"2026-06-16T00:35:07.76223Z","end":"2026-06-16T00:35:07.959408Z","steps":["trace[1127118443] 'process raft request' (duration: 194.042368ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:26.665217Z","caller":"traceutil/trace.go:171","msg":"trace[3019604] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"135.587317ms","start":"2026-06-16T00:35:26.529593Z","end":"2026-06-16T00:35:26.66518Z","steps":["trace[3019604] 'process raft request' (duration: 135.396012ms)"],"step_count":1}