{"level":"warn","ts":"2026-04-07T00:01:10.801333Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:01:10.80147Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-04-07T00:01:10.801612Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:01:10.80163Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-07T00:01:10.801805Z","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-04-07T00:01:10.803168Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-07T00:01:10.80331Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-04-07T00:01:10.808381Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.708345ms"} {"level":"info","ts":"2026-04-07T00:01:10.813265Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-07T00:01:10.813329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:01:10.813357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-07T00:01:10.813378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:01:10.813396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-07T00:01:10.813445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-07T00:01:10.897552Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:01:10.899658Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:01:10.900821Z","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-04-07T00:01:10.903436Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:01:10.903774Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:01:10.903914Z","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-04-07T00:01:10.903953Z","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-04-07T00:01:10.903964Z","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-04-07T00:01:10.907096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-07T00:01:10.90968Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-07T00:01:10.91053Z","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-04-07T00:01:10.910716Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:01:10.910737Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:01:10.910732Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-07T00:01:10.910809Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-07T00:01:11.714008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:01:11.714096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:01:11.71419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-07T00:01:11.714223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:01:11.714241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-07T00:01:11.714265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-07T00:01:11.71428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-07T00:01:11.72045Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:11.721905Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:01:11.722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:01:11.722054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:01:11.722472Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:11.722687Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:01:11.722744Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:11.722788Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:01:11.722837Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:01:11.72626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:01:11.726455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-07T00:01:17.533072Z","caller":"traceutil/trace.go:171","msg":"trace[1390509769] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"120.1277ms","start":"2026-04-07T00:01:17.412911Z","end":"2026-04-07T00:01:17.533038Z","steps":["trace[1390509769] 'process raft request' (duration: 119.900648ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:17.69358Z","caller":"traceutil/trace.go:171","msg":"trace[48943872] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"131.944809ms","start":"2026-04-07T00:01:17.561595Z","end":"2026-04-07T00:01:17.69354Z","steps":["trace[48943872] 'process raft request' (duration: 86.950054ms)","trace[48943872] 'compare' (duration: 44.618693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:18.627339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.675144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-07T00:01:18.627475Z","caller":"traceutil/trace.go:171","msg":"trace[512274040] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:283; }","duration":"115.881335ms","start":"2026-04-07T00:01:18.511567Z","end":"2026-04-07T00:01:18.627448Z","steps":["trace[512274040] 'range keys from in-memory index tree' (duration: 115.466203ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:20.17453Z","caller":"traceutil/trace.go:171","msg":"trace[374568440] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"344.306833ms","start":"2026-04-07T00:01:19.830197Z","end":"2026-04-07T00:01:20.174504Z","steps":["trace[374568440] 'read index received' (duration: 252.194332ms)","trace[374568440] 'applied index is now lower than readState.Index' (duration: 92.111501ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:20.174623Z","caller":"traceutil/trace.go:171","msg":"trace[129750685] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"412.184829ms","start":"2026-04-07T00:01:19.762409Z","end":"2026-04-07T00:01:20.174594Z","steps":["trace[129750685] 'process raft request' (duration: 320.002967ms)","trace[129750685] 'compare' (duration: 91.949301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:20.174699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.488924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:01:20.174738Z","caller":"traceutil/trace.go:171","msg":"trace[1254081999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:297; }","duration":"344.584326ms","start":"2026-04-07T00:01:19.830144Z","end":"2026-04-07T00:01:20.174728Z","steps":["trace[1254081999] 'agreement among raft nodes before linearized reading' (duration: 344.461375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:20.174803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:19.830122Z","time spent":"344.673736ms","remote":"127.0.0.1:39584","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-04-07T00:01:20.175664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:19.762374Z","time spent":"412.303279ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:01:20.454276Z","caller":"traceutil/trace.go:171","msg":"trace[1369568458] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"165.501082ms","start":"2026-04-07T00:01:20.288751Z","end":"2026-04-07T00:01:20.454252Z","steps":["trace[1369568458] 'process raft request' (duration: 165.443962ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:20.454559Z","caller":"traceutil/trace.go:171","msg":"trace[1217535610] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"168.104524ms","start":"2026-04-07T00:01:20.286222Z","end":"2026-04-07T00:01:20.454326Z","steps":["trace[1217535610] 'process raft request' (duration: 165.792513ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:20.497138Z","caller":"traceutil/trace.go:171","msg":"trace[181618554] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:313; }","duration":"184.090433ms","start":"2026-04-07T00:01:20.313023Z","end":"2026-04-07T00:01:20.497113Z","steps":["trace[181618554] 'read index received' (duration: 138.963412ms)","trace[181618554] 'applied index is now lower than readState.Index' (duration: 45.126091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:20.497339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.317584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-07T00:01:20.497381Z","caller":"traceutil/trace.go:171","msg":"trace[342157585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:305; }","duration":"184.410685ms","start":"2026-04-07T00:01:20.312959Z","end":"2026-04-07T00:01:20.49737Z","steps":["trace[342157585] 'agreement among raft nodes before linearized reading' (duration: 184.280964ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:20.497373Z","caller":"traceutil/trace.go:171","msg":"trace[2133091191] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"188.531474ms","start":"2026-04-07T00:01:20.308775Z","end":"2026-04-07T00:01:20.497306Z","steps":["trace[2133091191] 'process raft request' (duration: 188.222733ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:20.618931Z","caller":"traceutil/trace.go:171","msg":"trace[2016358537] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"118.051149ms","start":"2026-04-07T00:01:20.500864Z","end":"2026-04-07T00:01:20.618915Z","steps":["trace[2016358537] 'process raft request' (duration: 118.013679ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:20.618952Z","caller":"traceutil/trace.go:171","msg":"trace[1017188639] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"121.720667ms","start":"2026-04-07T00:01:20.497213Z","end":"2026-04-07T00:01:20.618933Z","steps":["trace[1017188639] 'read index received' (duration: 98.757415ms)","trace[1017188639] 'applied index is now lower than readState.Index' (duration: 22.961042ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:20.619061Z","caller":"traceutil/trace.go:171","msg":"trace[1653411386] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"160.453648ms","start":"2026-04-07T00:01:20.458598Z","end":"2026-04-07T00:01:20.619052Z","steps":["trace[1653411386] 'process raft request' (duration: 137.359614ms)","trace[1653411386] 'compare' (duration: 22.839173ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:20.619121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.588638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-07T00:01:20.619187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.174787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-07T00:01:20.619196Z","caller":"traceutil/trace.go:171","msg":"trace[1755519832] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:307; }","duration":"207.693159ms","start":"2026-04-07T00:01:20.411486Z","end":"2026-04-07T00:01:20.619179Z","steps":["trace[1755519832] 'agreement among raft nodes before linearized reading' (duration: 207.545109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:20.619133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.187132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-07T00:01:20.619211Z","caller":"traceutil/trace.go:171","msg":"trace[1935915790] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:307; }","duration":"301.217048ms","start":"2026-04-07T00:01:20.317986Z","end":"2026-04-07T00:01:20.619203Z","steps":["trace[1935915790] 'agreement among raft nodes before linearized reading' (duration: 301.179857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:20.619304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:20.31797Z","time spent":"301.325688ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-04-07T00:01:20.61927Z","caller":"traceutil/trace.go:171","msg":"trace[200855623] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:307; }","duration":"257.338343ms","start":"2026-04-07T00:01:20.361916Z","end":"2026-04-07T00:01:20.619255Z","steps":["trace[200855623] 'agreement among raft nodes before linearized reading' (duration: 257.118502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:20.619336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.275417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-07T00:01:20.619483Z","caller":"traceutil/trace.go:171","msg":"trace[1641303606] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:307; }","duration":"158.481248ms","start":"2026-04-07T00:01:20.460956Z","end":"2026-04-07T00:01:20.619437Z","steps":["trace[1641303606] 'agreement among raft nodes before linearized reading' (duration: 158.133276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:20.619151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.99054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-07T00:01:20.619641Z","caller":"traceutil/trace.go:171","msg":"trace[881911514] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:307; }","duration":"108.508903ms","start":"2026-04-07T00:01:20.511113Z","end":"2026-04-07T00:01:20.619622Z","steps":["trace[881911514] 'agreement among raft nodes before linearized reading' (duration: 108.00554ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:20.779845Z","caller":"traceutil/trace.go:171","msg":"trace[325407379] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"106.920494ms","start":"2026-04-07T00:01:20.672895Z","end":"2026-04-07T00:01:20.779815Z","steps":["trace[325407379] 'process raft request' (duration: 90.775955ms)","trace[325407379] 'compare' (duration: 15.639747ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:20.78031Z","caller":"traceutil/trace.go:171","msg":"trace[1579819236] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"106.953445ms","start":"2026-04-07T00:01:20.673105Z","end":"2026-04-07T00:01:20.780059Z","steps":["trace[1579819236] 'process raft request' (duration: 106.364782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:21.528336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.593016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:01:21.52866Z","caller":"traceutil/trace.go:171","msg":"trace[1664575941] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"322.796345ms","start":"2026-04-07T00:01:21.20584Z","end":"2026-04-07T00:01:21.528636Z","steps":["trace[1664575941] 'process raft request' (duration: 322.716865ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:21.528699Z","caller":"traceutil/trace.go:171","msg":"trace[1218264166] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"326.325913ms","start":"2026-04-07T00:01:21.202336Z","end":"2026-04-07T00:01:21.528662Z","steps":["trace[1218264166] 'process raft request' (duration: 326.151772ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:21.528657Z","caller":"traceutil/trace.go:171","msg":"trace[1882541730] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"327.627989ms","start":"2026-04-07T00:01:21.200941Z","end":"2026-04-07T00:01:21.528569Z","steps":["trace[1882541730] 'process raft request' (duration: 188.47045ms)","trace[1882541730] 'compare' (duration: 138.386565ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:21.528919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:21.202326Z","time spent":"326.475693ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:01:21.528994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:21.205813Z","time spent":"322.903046ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:01:21.529027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:21.200916Z","time spent":"327.94631ms","remote":"127.0.0.1:39426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:01:21.768884Z","caller":"traceutil/trace.go:171","msg":"trace[1448333887] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"143.58013ms","start":"2026-04-07T00:01:21.625267Z","end":"2026-04-07T00:01:21.768847Z","steps":["trace[1448333887] 'process raft request' (duration: 118.275587ms)","trace[1448333887] 'compare' (duration: 25.131803ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:22.370359Z","caller":"traceutil/trace.go:171","msg":"trace[1735899287] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"103.598533ms","start":"2026-04-07T00:01:22.266741Z","end":"2026-04-07T00:01:22.37034Z","steps":["trace[1735899287] 'process raft request' (duration: 90.68978ms)","trace[1735899287] 'compare' (duration: 12.768542ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:22.3707Z","caller":"traceutil/trace.go:171","msg":"trace[1133656698] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"103.796354ms","start":"2026-04-07T00:01:22.266882Z","end":"2026-04-07T00:01:22.370678Z","steps":["trace[1133656698] 'process raft request' (duration: 103.412702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:23.389798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.613932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-lr47m\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-04-07T00:01:23.38989Z","caller":"traceutil/trace.go:171","msg":"trace[1391380490] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-lr47m; range_end:; response_count:1; response_revision:355; }","duration":"120.797544ms","start":"2026-04-07T00:01:23.26907Z","end":"2026-04-07T00:01:23.389868Z","steps":["trace[1391380490] 'range keys from in-memory index tree' (duration: 120.428282ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:23.527422Z","caller":"traceutil/trace.go:171","msg":"trace[2140166932] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"127.596816ms","start":"2026-04-07T00:01:23.399792Z","end":"2026-04-07T00:01:23.527389Z","steps":["trace[2140166932] 'process raft request' (duration: 98.032253ms)","trace[2140166932] 'compare' (duration: 29.410593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:34.987332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366839404535929,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T00:01:35.157251Z","caller":"traceutil/trace.go:171","msg":"trace[592635570] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"670.947377ms","start":"2026-04-07T00:01:34.486283Z","end":"2026-04-07T00:01:35.157231Z","steps":["trace[592635570] 'read index received' (duration: 670.777514ms)","trace[592635570] 'applied index is now lower than readState.Index' (duration: 168.993µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:35.157293Z","caller":"traceutil/trace.go:171","msg":"trace[936317923] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"745.726109ms","start":"2026-04-07T00:01:34.411547Z","end":"2026-04-07T00:01:35.157273Z","steps":["trace[936317923] 'process raft request' (duration: 745.550796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:35.157394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.09922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4176"} {"level":"info","ts":"2026-04-07T00:01:35.157424Z","caller":"traceutil/trace.go:171","msg":"trace[582744950] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:389; }","duration":"671.164624ms","start":"2026-04-07T00:01:34.486249Z","end":"2026-04-07T00:01:35.157413Z","steps":["trace[582744950] 'agreement among raft nodes before linearized reading' (duration: 671.089528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:35.157421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:34.411523Z","time spent":"745.821377ms","remote":"127.0.0.1:39584","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-04-07T00:01:35.157444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:34.48623Z","time spent":"671.209588ms","remote":"127.0.0.1:39530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4199,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T00:01:35.157572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.63636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T00:01:35.157636Z","caller":"traceutil/trace.go:171","msg":"trace[782972117] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:389; }","duration":"628.703456ms","start":"2026-04-07T00:01:34.528887Z","end":"2026-04-07T00:01:35.157591Z","steps":["trace[782972117] 'agreement among raft nodes before linearized reading' (duration: 628.649362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:35.157665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:34.528864Z","time spent":"628.795083ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-07T00:01:35.157903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.407328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:35.157938Z","caller":"traceutil/trace.go:171","msg":"trace[429621878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"593.477264ms","start":"2026-04-07T00:01:34.564451Z","end":"2026-04-07T00:01:35.157928Z","steps":["trace[429621878] 'agreement among raft nodes before linearized reading' (duration: 593.42054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:35.157989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:34.564432Z","time spent":"593.55101ms","remote":"127.0.0.1:39338","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-04-07T00:01:35.347896Z","caller":"traceutil/trace.go:171","msg":"trace[1909462226] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:403; }","duration":"184.531495ms","start":"2026-04-07T00:01:35.163342Z","end":"2026-04-07T00:01:35.347874Z","steps":["trace[1909462226] 'read index received' (duration: 90.701427ms)","trace[1909462226] 'applied index is now lower than readState.Index' (duration: 93.829228ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:35.348019Z","caller":"traceutil/trace.go:171","msg":"trace[875790597] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"185.725808ms","start":"2026-04-07T00:01:35.162285Z","end":"2026-04-07T00:01:35.34801Z","steps":["trace[875790597] 'process raft request' (duration: 185.490409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:35.348156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.606049ms","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-04-07T00:01:35.348236Z","caller":"traceutil/trace.go:171","msg":"trace[679919989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:390; }","duration":"158.74256ms","start":"2026-04-07T00:01:35.189472Z","end":"2026-04-07T00:01:35.348215Z","steps":["trace[679919989] 'agreement among raft nodes before linearized reading' (duration: 158.618821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:35.348203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.831169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:35.348299Z","caller":"traceutil/trace.go:171","msg":"trace[1760245025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"184.995361ms","start":"2026-04-07T00:01:35.163283Z","end":"2026-04-07T00:01:35.348279Z","steps":["trace[1760245025] 'agreement among raft nodes before linearized reading' (duration: 184.822998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:35.348331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.896496ms","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-04-07T00:01:35.348408Z","caller":"traceutil/trace.go:171","msg":"trace[2065970044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:390; }","duration":"112.022176ms","start":"2026-04-07T00:01:35.23637Z","end":"2026-04-07T00:01:35.348393Z","steps":["trace[2065970044] 'agreement among raft nodes before linearized reading' (duration: 111.878935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:49.913506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366839404536181,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T00:01:50.17041Z","caller":"traceutil/trace.go:171","msg":"trace[1925868828] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"856.478668ms","start":"2026-04-07T00:01:49.312567Z","end":"2026-04-07T00:01:50.169046Z","steps":["trace[1925868828] 'process raft request' (duration: 820.905122ms)","trace[1925868828] 'compare' (duration: 34.644317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:50.170594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:49.312548Z","time spent":"857.958672ms","remote":"127.0.0.1:39408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:01:50.171134Z","caller":"traceutil/trace.go:171","msg":"trace[938594982] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:562; }","duration":"758.420771ms","start":"2026-04-07T00:01:49.412657Z","end":"2026-04-07T00:01:50.171078Z","steps":["trace[938594982] 'read index received' (duration: 720.531977ms)","trace[938594982] 'applied index is now lower than readState.Index' (duration: 37.887324ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:01:50.171597Z","caller":"traceutil/trace.go:171","msg":"trace[2000313036] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"851.941134ms","start":"2026-04-07T00:01:49.319638Z","end":"2026-04-07T00:01:50.171579Z","steps":["trace[2000313036] 'process raft request' (duration: 850.298889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:50.171685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:49.319611Z","time spent":"852.041159ms","remote":"127.0.0.1:39408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:01:50.172025Z","caller":"traceutil/trace.go:171","msg":"trace[1778755638] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"859.306498ms","start":"2026-04-07T00:01:49.312703Z","end":"2026-04-07T00:01:50.172009Z","steps":["trace[1778755638] 'process raft request' (duration: 856.144487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:50.172133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:49.31267Z","time spent":"859.394854ms","remote":"127.0.0.1:39408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:01:50.172631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.062096ms","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-04-07T00:01:50.172688Z","caller":"traceutil/trace.go:171","msg":"trace[159447932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"749.168152ms","start":"2026-04-07T00:01:49.423499Z","end":"2026-04-07T00:01:50.172668Z","steps":["trace[159447932] 'agreement among raft nodes before linearized reading' (duration: 748.293616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:50.172719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:49.423469Z","time spent":"749.241377ms","remote":"127.0.0.1:39584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:01:50.172917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.448954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:50.173048Z","caller":"traceutil/trace.go:171","msg":"trace[806551710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"608.585392ms","start":"2026-04-07T00:01:49.564446Z","end":"2026-04-07T00:01:50.173032Z","steps":["trace[806551710] 'agreement among raft nodes before linearized reading' (duration: 608.134514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:50.173104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:49.564386Z","time spent":"608.69844ms","remote":"127.0.0.1:39338","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-04-07T00:01:50.173131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.44255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T00:01:50.173192Z","caller":"traceutil/trace.go:171","msg":"trace[1337663541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"749.517525ms","start":"2026-04-07T00:01:49.423664Z","end":"2026-04-07T00:01:50.173181Z","steps":["trace[1337663541] 'agreement among raft nodes before linearized reading' (duration: 749.425849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:50.173226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:49.423656Z","time spent":"749.556427ms","remote":"127.0.0.1:39584","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-04-07T00:01:50.173347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.691156ms","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-04-07T00:01:50.173593Z","caller":"traceutil/trace.go:171","msg":"trace[108761839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"761.026587ms","start":"2026-04-07T00:01:49.412547Z","end":"2026-04-07T00:01:50.173574Z","steps":["trace[108761839] 'agreement among raft nodes before linearized reading' (duration: 759.162328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:50.173628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:49.412526Z","time spent":"761.088181ms","remote":"127.0.0.1:39584","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-04-07T00:01:50.404265Z","caller":"traceutil/trace.go:171","msg":"trace[69045751] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"159.798659ms","start":"2026-04-07T00:01:50.244438Z","end":"2026-04-07T00:01:50.404237Z","steps":["trace[69045751] 'process raft request' (duration: 157.985513ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:50.407742Z","caller":"traceutil/trace.go:171","msg":"trace[1072484616] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"140.849772ms","start":"2026-04-07T00:01:50.266864Z","end":"2026-04-07T00:01:50.407714Z","steps":["trace[1072484616] 'process raft request' (duration: 140.719074ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:51.140268Z","caller":"traceutil/trace.go:171","msg":"trace[2040659386] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"389.643139ms","start":"2026-04-07T00:01:50.750598Z","end":"2026-04-07T00:01:51.140241Z","steps":["trace[2040659386] 'process raft request' (duration: 364.941375ms)","trace[2040659386] 'compare' (duration: 24.508742ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:51.140376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:50.750566Z","time spent":"389.765636ms","remote":"127.0.0.1:39408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:01:51.154423Z","caller":"traceutil/trace.go:171","msg":"trace[57501033] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:579; }","duration":"239.239987ms","start":"2026-04-07T00:01:50.915157Z","end":"2026-04-07T00:01:51.154397Z","steps":["trace[57501033] 'read index received' (duration: 200.293137ms)","trace[57501033] 'applied index is now lower than readState.Index' (duration: 38.94584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:51.154858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.641032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.154935Z","caller":"traceutil/trace.go:171","msg":"trace[1584258879] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:560; }","duration":"239.710997ms","start":"2026-04-07T00:01:50.915187Z","end":"2026-04-07T00:01:51.154898Z","steps":["trace[1584258879] 'agreement among raft nodes before linearized reading' (duration: 239.617801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.154916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.711166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.155009Z","caller":"traceutil/trace.go:171","msg":"trace[1029842436] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:560; }","duration":"239.832184ms","start":"2026-04-07T00:01:50.915154Z","end":"2026-04-07T00:01:51.154986Z","steps":["trace[1029842436] 'agreement among raft nodes before linearized reading' (duration: 239.630551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.155034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.751797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.155066Z","caller":"traceutil/trace.go:171","msg":"trace[122846372] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"121.800921ms","start":"2026-04-07T00:01:51.033255Z","end":"2026-04-07T00:01:51.155056Z","steps":["trace[122846372] 'agreement among raft nodes before linearized reading' (duration: 121.743867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.155141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.7349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.155175Z","caller":"traceutil/trace.go:171","msg":"trace[640954481] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:560; }","duration":"164.788694ms","start":"2026-04-07T00:01:50.990377Z","end":"2026-04-07T00:01:51.155166Z","steps":["trace[640954481] 'agreement among raft nodes before linearized reading' (duration: 164.739331ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:51.155227Z","caller":"traceutil/trace.go:171","msg":"trace[1753436067] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"280.878368ms","start":"2026-04-07T00:01:50.874336Z","end":"2026-04-07T00:01:51.155215Z","steps":["trace[1753436067] 'process raft request' (duration: 279.936479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.155331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.013098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:01:51.155482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.344637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.155516Z","caller":"traceutil/trace.go:171","msg":"trace[1753809559] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"216.396351ms","start":"2026-04-07T00:01:50.939108Z","end":"2026-04-07T00:01:51.155505Z","steps":["trace[1753809559] 'agreement among raft nodes before linearized reading' (duration: 216.343857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.155657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.34975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.155687Z","caller":"traceutil/trace.go:171","msg":"trace[976988322] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:560; }","duration":"165.416074ms","start":"2026-04-07T00:01:50.990263Z","end":"2026-04-07T00:01:51.155679Z","steps":["trace[976988322] 'agreement among raft nodes before linearized reading' (duration: 165.369781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.155723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.027049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.155763Z","caller":"traceutil/trace.go:171","msg":"trace[1132402861] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:560; }","duration":"203.066582ms","start":"2026-04-07T00:01:50.952675Z","end":"2026-04-07T00:01:51.155741Z","steps":["trace[1132402861] 'agreement among raft nodes before linearized reading' (duration: 203.022439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.155786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.256976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:01:51.155803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.133206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.15581Z","caller":"traceutil/trace.go:171","msg":"trace[1046465505] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"217.293508ms","start":"2026-04-07T00:01:50.938508Z","end":"2026-04-07T00:01:51.155802Z","steps":["trace[1046465505] 'agreement among raft nodes before linearized reading' (duration: 217.253456ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:51.155828Z","caller":"traceutil/trace.go:171","msg":"trace[720464355] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:560; }","duration":"203.160378ms","start":"2026-04-07T00:01:50.952661Z","end":"2026-04-07T00:01:51.155821Z","steps":["trace[720464355] 'agreement among raft nodes before linearized reading' (duration: 203.120515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.155881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.291702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:01:51.155901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.263876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.155921Z","caller":"traceutil/trace.go:171","msg":"trace[142856359] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:560; }","duration":"202.320314ms","start":"2026-04-07T00:01:50.953572Z","end":"2026-04-07T00:01:51.155893Z","steps":["trace[142856359] 'agreement among raft nodes before linearized reading' (duration: 202.289692ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:51.155924Z","caller":"traceutil/trace.go:171","msg":"trace[952516811] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"217.294438ms","start":"2026-04-07T00:01:50.938621Z","end":"2026-04-07T00:01:51.155916Z","steps":["trace[952516811] 'agreement among raft nodes before linearized reading' (duration: 217.260375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.156145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.819024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.156169Z","caller":"traceutil/trace.go:171","msg":"trace[515673787] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"122.855247ms","start":"2026-04-07T00:01:51.033307Z","end":"2026-04-07T00:01:51.156163Z","steps":["trace[515673787] 'agreement among raft nodes before linearized reading' (duration: 122.816405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:51.156262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.060587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:51.156284Z","caller":"traceutil/trace.go:171","msg":"trace[269919986] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"122.123701ms","start":"2026-04-07T00:01:51.034153Z","end":"2026-04-07T00:01:51.156277Z","steps":["trace[269919986] 'agreement among raft nodes before linearized reading' (duration: 122.088959ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:51.155367Z","caller":"traceutil/trace.go:171","msg":"trace[2145527723] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:560; }","duration":"165.089573ms","start":"2026-04-07T00:01:50.990266Z","end":"2026-04-07T00:01:51.155356Z","steps":["trace[2145527723] 'agreement among raft nodes before linearized reading' (duration: 165.03411ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:56.233413Z","caller":"traceutil/trace.go:171","msg":"trace[1697691072] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"300.856246ms","start":"2026-04-07T00:01:55.932448Z","end":"2026-04-07T00:01:56.233304Z","steps":["trace[1697691072] 'process raft request' (duration: 300.129823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:56.23378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:55.932416Z","time spent":"301.238618ms","remote":"127.0.0.1:39444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:01:57.138293Z","caller":"traceutil/trace.go:171","msg":"trace[933781124] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"406.507976ms","start":"2026-04-07T00:01:56.731744Z","end":"2026-04-07T00:01:57.138252Z","steps":["trace[933781124] 'process raft request' (duration: 405.616374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:57.138578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:56.731722Z","time spent":"406.726239ms","remote":"127.0.0.1:39444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:01:58.062841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334366839404536356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:01:58.561327Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.141372156s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T00:01:58.699526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349360112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xzqn6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-07T00:01:58.699628Z","caller":"traceutil/trace.go:171","msg":"trace[41019101] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xzqn6; range_end:; response_count:1; response_revision:589; }","duration":"1.34950324s","start":"2026-04-07T00:01:57.3501Z","end":"2026-04-07T00:01:58.699603Z","steps":["trace[41019101] 'range keys from in-memory index tree' (duration: 1.349112027s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:58.699671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:57.35008Z","time spent":"1.349581064s","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-xzqn6\" "} {"level":"warn","ts":"2026-04-07T00:01:58.699954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404721502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T00:01:58.700099Z","caller":"traceutil/trace.go:171","msg":"trace[1687743339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"1.404919184s","start":"2026-04-07T00:01:57.29515Z","end":"2026-04-07T00:01:58.700069Z","steps":["trace[1687743339] 'range keys from in-memory index tree' (duration: 1.404501009s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:58.700144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.485831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-07T00:01:58.700169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:57.29512Z","time spent":"1.40503339s","remote":"127.0.0.1:39584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T00:01:58.700234Z","caller":"traceutil/trace.go:171","msg":"trace[888132284] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.280362775s","start":"2026-04-07T00:01:57.419851Z","end":"2026-04-07T00:01:58.700213Z","steps":["trace[888132284] 'process raft request' (duration: 1.141718435s)","trace[888132284] 'compare' (duration: 138.157632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:58.700313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:57.419823Z","time spent":"1.28044617s","remote":"127.0.0.1:39584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:01:58.706011Z","caller":"traceutil/trace.go:171","msg":"trace[956628394] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"1.143759791s","start":"2026-04-07T00:01:57.562224Z","end":"2026-04-07T00:01:58.705984Z","steps":["trace[956628394] 'read index received' (duration: 999.421322ms)","trace[956628394] 'applied index is now lower than readState.Index' (duration: 144.337609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:01:58.706137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143906919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:01:58.70611Z","caller":"traceutil/trace.go:171","msg":"trace[652032608] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.08170632s","start":"2026-04-07T00:01:57.624327Z","end":"2026-04-07T00:01:58.706034Z","steps":["trace[652032608] 'process raft request' (duration: 1.081254244s)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:01:58.706167Z","caller":"traceutil/trace.go:171","msg":"trace[1337000601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"1.143999375s","start":"2026-04-07T00:01:57.562156Z","end":"2026-04-07T00:01:58.706156Z","steps":["trace[1337000601] 'agreement among raft nodes before linearized reading' (duration: 1.143933981s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:58.706481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:57.624297Z","time spent":"1.081937383s","remote":"127.0.0.1:39444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:01:58.706574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:57.562129Z","time spent":"1.1444321s","remote":"127.0.0.1:39338","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-04-07T00:01:58.726847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.70553ms","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-04-07T00:01:58.726916Z","caller":"traceutil/trace.go:171","msg":"trace[1992311488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"473.792315ms","start":"2026-04-07T00:01:58.253106Z","end":"2026-04-07T00:01:58.726898Z","steps":["trace[1992311488] 'agreement among raft nodes before linearized reading' (duration: 473.678208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:58.726938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:58.253095Z","time spent":"473.838468ms","remote":"127.0.0.1:39584","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":"warn","ts":"2026-04-07T00:01:58.727064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.633443ms","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-04-07T00:01:58.727093Z","caller":"traceutil/trace.go:171","msg":"trace[1090415887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"474.707208ms","start":"2026-04-07T00:01:58.252375Z","end":"2026-04-07T00:01:58.727083Z","steps":["trace[1090415887] 'agreement among raft nodes before linearized reading' (duration: 474.649414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:58.727108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:58.252352Z","time spent":"474.752721ms","remote":"127.0.0.1:39584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:01:58.727229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.107384ms","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-04-07T00:01:58.727254Z","caller":"traceutil/trace.go:171","msg":"trace[1694190995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"474.157016ms","start":"2026-04-07T00:01:58.253088Z","end":"2026-04-07T00:01:58.727245Z","steps":["trace[1694190995] 'agreement among raft nodes before linearized reading' (duration: 473.88203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:01:58.727268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:01:58.253074Z","time spent":"474.190308ms","remote":"127.0.0.1:39584","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":"info","ts":"2026-04-07T00:02:00.641233Z","caller":"traceutil/trace.go:171","msg":"trace[621617886] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"154.276405ms","start":"2026-04-07T00:02:00.486931Z","end":"2026-04-07T00:02:00.641207Z","steps":["trace[621617886] 'process raft request' (duration: 154.108245ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:14.308093Z","caller":"traceutil/trace.go:171","msg":"trace[1177404528] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"393.791773ms","start":"2026-04-07T00:02:13.914271Z","end":"2026-04-07T00:02:14.308063Z","steps":["trace[1177404528] 'read index received' (duration: 393.636406ms)","trace[1177404528] 'applied index is now lower than readState.Index' (duration: 154.067µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:14.308271Z","caller":"traceutil/trace.go:171","msg":"trace[514615991] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"452.11527ms","start":"2026-04-07T00:02:13.856142Z","end":"2026-04-07T00:02:14.308258Z","steps":["trace[514615991] 'process raft request' (duration: 451.732911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:14.308385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:13.85611Z","time spent":"452.191584ms","remote":"127.0.0.1:39584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:02:14.308491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.179966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-07T00:02:14.308561Z","caller":"traceutil/trace.go:171","msg":"trace[1535001053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"124.289722ms","start":"2026-04-07T00:02:14.184253Z","end":"2026-04-07T00:02:14.308543Z","steps":["trace[1535001053] 'agreement among raft nodes before linearized reading' (duration: 124.114214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:14.308746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.445344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:02:14.308839Z","caller":"traceutil/trace.go:171","msg":"trace[980176653] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:762; }","duration":"394.593061ms","start":"2026-04-07T00:02:13.914226Z","end":"2026-04-07T00:02:14.308819Z","steps":["trace[980176653] 'agreement among raft nodes before linearized reading' (duration: 394.405852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:14.308887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:02:13.914161Z","time spent":"394.715677ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:02:20.942727Z","caller":"traceutil/trace.go:171","msg":"trace[1677077884] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:883; }","duration":"103.093237ms","start":"2026-04-07T00:02:20.839606Z","end":"2026-04-07T00:02:20.9427Z","steps":["trace[1677077884] 'read index received' (duration: 88.50877ms)","trace[1677077884] 'applied index is now lower than readState.Index' (duration: 14.583657ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:02:20.942845Z","caller":"traceutil/trace.go:171","msg":"trace[274847242] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"104.087471ms","start":"2026-04-07T00:02:20.838746Z","end":"2026-04-07T00:02:20.942833Z","steps":["trace[274847242] 'process raft request' (duration: 103.868612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:20.943018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.334098ms","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-04-07T00:02:20.943081Z","caller":"traceutil/trace.go:171","msg":"trace[188830330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"103.423262ms","start":"2026-04-07T00:02:20.839641Z","end":"2026-04-07T00:02:20.943064Z","steps":["trace[188830330] 'agreement among raft nodes before linearized reading' (duration: 103.230973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:02:20.943112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.452153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T00:02:20.943219Z","caller":"traceutil/trace.go:171","msg":"trace[1528601779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"103.584859ms","start":"2026-04-07T00:02:20.839573Z","end":"2026-04-07T00:02:20.943158Z","steps":["trace[1528601779] 'agreement among raft nodes before linearized reading' (duration: 103.358669ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:02:29.066873Z","caller":"traceutil/trace.go:171","msg":"trace[1053935987] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"101.738496ms","start":"2026-04-07T00:02:28.965107Z","end":"2026-04-07T00:02:29.066846Z","steps":["trace[1053935987] 'process raft request' (duration: 101.588129ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:03.331379Z","caller":"traceutil/trace.go:171","msg":"trace[1567283210] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"105.105844ms","start":"2026-04-07T00:03:03.226258Z","end":"2026-04-07T00:03:03.331363Z","steps":["trace[1567283210] 'process raft request' (duration: 104.797915ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:03:55.85088Z","caller":"traceutil/trace.go:171","msg":"trace[940744297] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"171.176494ms","start":"2026-04-07T00:03:55.679679Z","end":"2026-04-07T00:03:55.850856Z","steps":["trace[940744297] 'read index received' (duration: 170.99262ms)","trace[940744297] 'applied index is now lower than readState.Index' (duration: 182.884µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:03:55.850986Z","caller":"traceutil/trace.go:171","msg":"trace[561264288] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"217.537572ms","start":"2026-04-07T00:03:55.633424Z","end":"2026-04-07T00:03:55.850962Z","steps":["trace[561264288] 'process raft request' (duration: 217.255257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:03:55.851121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.418809ms","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-04-07T00:03:55.851162Z","caller":"traceutil/trace.go:171","msg":"trace[1624279101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1265; }","duration":"171.507061ms","start":"2026-04-07T00:03:55.679644Z","end":"2026-04-07T00:03:55.851151Z","steps":["trace[1624279101] 'agreement among raft nodes before linearized reading' (duration: 171.312677ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:04.932945Z","caller":"traceutil/trace.go:171","msg":"trace[748621845] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"369.749526ms","start":"2026-04-07T00:04:04.563168Z","end":"2026-04-07T00:04:04.932918Z","steps":["trace[748621845] 'read index received' (duration: 369.592843ms)","trace[748621845] 'applied index is now lower than readState.Index' (duration: 155.103µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:04:04.932964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:04:04.533972Z","time spent":"398.985212ms","remote":"127.0.0.1:39386","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T00:04:04.93313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.735321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:04:04.933172Z","caller":"traceutil/trace.go:171","msg":"trace[1704170864] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1301; }","duration":"135.828383ms","start":"2026-04-07T00:04:04.797332Z","end":"2026-04-07T00:04:04.93316Z","steps":["trace[1704170864] 'agreement among raft nodes before linearized reading' (duration: 135.732162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:04.93316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.94848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:04:04.933269Z","caller":"traceutil/trace.go:171","msg":"trace[191480453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"370.107973ms","start":"2026-04-07T00:04:04.563141Z","end":"2026-04-07T00:04:04.933249Z","steps":["trace[191480453] 'agreement among raft nodes before linearized reading' (duration: 369.911799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:04.933319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:04:04.563128Z","time spent":"370.182784ms","remote":"127.0.0.1:39338","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-04-07T00:04:25.405007Z","caller":"traceutil/trace.go:171","msg":"trace[496524332] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"112.123635ms","start":"2026-04-07T00:04:25.292866Z","end":"2026-04-07T00:04:25.40499Z","steps":["trace[496524332] 'read index received' (duration: 111.951823ms)","trace[496524332] 'applied index is now lower than readState.Index' (duration: 171.142µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:04:25.405115Z","caller":"traceutil/trace.go:171","msg":"trace[2035351482] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"136.370895ms","start":"2026-04-07T00:04:25.268714Z","end":"2026-04-07T00:04:25.405085Z","steps":["trace[2035351482] 'process raft request' (duration: 136.158392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:25.405155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.275308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:04:25.40539Z","caller":"traceutil/trace.go:171","msg":"trace[720568948] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1374; }","duration":"112.541102ms","start":"2026-04-07T00:04:25.292834Z","end":"2026-04-07T00:04:25.405375Z","steps":["trace[720568948] 'agreement among raft nodes before linearized reading' (duration: 112.255367ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:26.228292Z","caller":"traceutil/trace.go:171","msg":"trace[65153031] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"124.21938ms","start":"2026-04-07T00:04:26.104046Z","end":"2026-04-07T00:04:26.228266Z","steps":["trace[65153031] 'process raft request' (duration: 80.082668ms)","trace[65153031] 'compare' (duration: 44.031721ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:04:26.269337Z","caller":"traceutil/trace.go:171","msg":"trace[1733504752] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1434; }","duration":"154.213475ms","start":"2026-04-07T00:04:26.115106Z","end":"2026-04-07T00:04:26.26932Z","steps":["trace[1733504752] 'read index received' (duration: 69.032002ms)","trace[1733504752] 'applied index is now lower than readState.Index' (duration: 85.180703ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:04:26.269392Z","caller":"traceutil/trace.go:171","msg":"trace[1481869790] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"164.478568ms","start":"2026-04-07T00:04:26.104886Z","end":"2026-04-07T00:04:26.269365Z","steps":["trace[1481869790] 'process raft request' (duration: 164.350566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:04:26.269487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.93875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-system/\" range_end:\"/registry/jobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:04:26.269476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.350918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:496"} {"level":"info","ts":"2026-04-07T00:04:26.269533Z","caller":"traceutil/trace.go:171","msg":"trace[1019196722] range","detail":"{range_begin:/registry/jobs/kube-system/; range_end:/registry/jobs/kube-system0; response_count:0; response_revision:1378; }","duration":"129.047482ms","start":"2026-04-07T00:04:26.140476Z","end":"2026-04-07T00:04:26.269523Z","steps":["trace[1019196722] 'agreement among raft nodes before linearized reading' (duration: 128.94715ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:04:26.270003Z","caller":"traceutil/trace.go:171","msg":"trace[1018823559] range","detail":"{range_begin:/registry/rolebindings/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:1378; }","duration":"154.551701ms","start":"2026-04-07T00:04:26.115037Z","end":"2026-04-07T00:04:26.269589Z","steps":["trace[1018823559] 'agreement among raft nodes before linearized reading' (duration: 154.343387ms)"],"step_count":1}