{"level":"warn","ts":"2026-03-17T21:51:42.639836Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:51:42.639969Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.163: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.163:2380","--initial-cluster=instance=https://199.19.213.163:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.163:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.163: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-03-17T21:51:42.640058Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:51:42.640226Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-03-17T21:51:42.640501Z","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-03-17T21:51:42.641666Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"]} {"level":"info","ts":"2026-03-17T21:51:42.641842Z","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.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.163: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-03-17T21:51:42.64664Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.423837ms"} {"level":"info","ts":"2026-03-17T21:51:42.658391Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e7941a908d261618","cluster-id":"5b6cb15db6ea8c10"} {"level":"info","ts":"2026-03-17T21:51:42.65852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=()"} {"level":"info","ts":"2026-03-17T21:51:42.658561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 0"} {"level":"info","ts":"2026-03-17T21:51:42.658585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e7941a908d261618 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-17T21:51:42.658607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 1"} {"level":"info","ts":"2026-03-17T21:51:42.658666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"warn","ts":"2026-03-17T21:51:42.664535Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-17T21:51:42.666078Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-17T21:51:42.667448Z","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-03-17T21:51:42.669606Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e7941a908d261618","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-17T21:51:42.669779Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e7941a908d261618","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-17T21:51:42.670026Z","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-03-17T21:51:42.670122Z","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-03-17T21:51:42.670136Z","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-03-17T21:51:42.670862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"info","ts":"2026-03-17T21:51:42.671855Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","added-peer-id":"e7941a908d261618","added-peer-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-03-17T21:51:42.67389Z","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-03-17T21:51:42.674149Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e7941a908d261618","initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-17T21:51:42.674185Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-17T21:51:42.674257Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-03-17T21:51:42.674276Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-03-17T21:51:43.659815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 is starting a new election at term 1"} {"level":"info","ts":"2026-03-17T21:51:43.660126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-17T21:51:43.660207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgPreVoteResp from e7941a908d261618 at term 1"} {"level":"info","ts":"2026-03-17T21:51:43.660258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became candidate at term 2"} {"level":"info","ts":"2026-03-17T21:51:43.660296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgVoteResp from e7941a908d261618 at term 2"} {"level":"info","ts":"2026-03-17T21:51:43.660363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became leader at term 2"} {"level":"info","ts":"2026-03-17T21:51:43.660443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e7941a908d261618 elected leader e7941a908d261618 at term 2"} {"level":"info","ts":"2026-03-17T21:51:43.661516Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e7941a908d261618","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.163:2379]}","request-path":"/0/members/e7941a908d261618/attributes","cluster-id":"5b6cb15db6ea8c10","publish-timeout":"7s"} {"level":"info","ts":"2026-03-17T21:51:43.661746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:51:43.661797Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:51:43.661892Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:51:43.662112Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-17T21:51:43.662171Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-17T21:51:43.663014Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:51:43.663089Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:51:43.663132Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:51:43.66426Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-17T21:51:43.665526Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.163:2379"} {"level":"info","ts":"2026-03-17T21:51:47.640427Z","caller":"traceutil/trace.go:171","msg":"trace[760086056] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"606.704096ms","start":"2026-03-17T21:51:47.033692Z","end":"2026-03-17T21:51:47.640396Z","steps":["trace[760086056] 'process raft request' (duration: 606.507516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:47.641098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:47.033675Z","time spent":"606.876474ms","remote":"127.0.0.1:57046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:51:48.761914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.349475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:51:48.762131Z","caller":"traceutil/trace.go:171","msg":"trace[1249088959] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"1.117627094s","start":"2026-03-17T21:51:47.644471Z","end":"2026-03-17T21:51:48.762098Z","steps":["trace[1249088959] 'process raft request' (duration: 1.117575921s)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:48.762153Z","caller":"traceutil/trace.go:171","msg":"trace[200307361] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"1.576878739s","start":"2026-03-17T21:51:47.185163Z","end":"2026-03-17T21:51:48.762042Z","steps":["trace[200307361] 'process raft request' (duration: 924.189518ms)","trace[200307361] 'compare' (duration: 652.194367ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:51:48.762197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:47.644448Z","time spent":"1.117723808s","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:51:48.762277Z","caller":"traceutil/trace.go:171","msg":"trace[572027394] linearizableReadLoop","detail":"{readStateIndex:203; appliedIndex:201; }","duration":"1.426420341s","start":"2026-03-17T21:51:47.335805Z","end":"2026-03-17T21:51:48.762225Z","steps":["trace[572027394] 'read index received' (duration: 304.381349ms)","trace[572027394] 'applied index is now lower than readState.Index' (duration: 1.121994599s)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:51:48.762305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:47.185137Z","time spent":"1.577101741s","remote":"127.0.0.1:57110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-17T21:51:48.762499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.15428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-17T21:51:48.7625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426690394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:51:48.76256Z","caller":"traceutil/trace.go:171","msg":"trace[357203020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:200; }","duration":"973.259815ms","start":"2026-03-17T21:51:47.789284Z","end":"2026-03-17T21:51:48.762544Z","steps":["trace[357203020] 'agreement among raft nodes before linearized reading' (duration: 973.16645ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:48.762683Z","caller":"traceutil/trace.go:171","msg":"trace[1470599562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:200; }","duration":"1.426877663s","start":"2026-03-17T21:51:47.335753Z","end":"2026-03-17T21:51:48.76263Z","steps":["trace[1470599562] 'agreement among raft nodes before linearized reading' (duration: 1.426637252s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:48.762817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:47.335725Z","time spent":"1.427076252s","remote":"127.0.0.1:57172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T21:51:48.76273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:51:47.789264Z","time spent":"973.382621ms","remote":"127.0.0.1:57172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-17T21:51:48.905058Z","caller":"traceutil/trace.go:171","msg":"trace[676496503] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"138.047615ms","start":"2026-03-17T21:51:48.76698Z","end":"2026-03-17T21:51:48.905028Z","steps":["trace[676496503] 'process raft request' (duration: 126.298395ms)","trace[676496503] 'compare' (duration: 11.629514ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:48.907296Z","caller":"traceutil/trace.go:171","msg":"trace[248028865] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"139.658736ms","start":"2026-03-17T21:51:48.767603Z","end":"2026-03-17T21:51:48.907262Z","steps":["trace[248028865] 'process raft request' (duration: 139.592672ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:48.907304Z","caller":"traceutil/trace.go:171","msg":"trace[855559241] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"139.853144ms","start":"2026-03-17T21:51:48.767427Z","end":"2026-03-17T21:51:48.90728Z","steps":["trace[855559241] 'process raft request' (duration: 139.667055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:50.474944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.365105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:51:50.475071Z","caller":"traceutil/trace.go:171","msg":"trace[687878653] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"136.805508ms","start":"2026-03-17T21:51:50.338246Z","end":"2026-03-17T21:51:50.475051Z","steps":["trace[687878653] 'process raft request' (duration: 15.257144ms)","trace[687878653] 'compare' (duration: 121.207578ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:50.48986Z","caller":"traceutil/trace.go:171","msg":"trace[2078283717] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"126.160232ms","start":"2026-03-17T21:51:50.363669Z","end":"2026-03-17T21:51:50.489829Z","steps":["trace[2078283717] 'process raft request' (duration: 125.960292ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:50.501505Z","caller":"traceutil/trace.go:171","msg":"trace[336850251] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"107.368752ms","start":"2026-03-17T21:51:50.394088Z","end":"2026-03-17T21:51:50.501457Z","steps":["trace[336850251] 'process raft request' (duration: 107.262357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:50.501541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.828845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:51:50.501699Z","caller":"traceutil/trace.go:171","msg":"trace[288065502] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:253; }","duration":"108.022754ms","start":"2026-03-17T21:51:50.393661Z","end":"2026-03-17T21:51:50.501684Z","steps":["trace[288065502] 'agreement among raft nodes before linearized reading' (duration: 107.816754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:50.723206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.15792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-03-17T21:51:50.723334Z","caller":"traceutil/trace.go:171","msg":"trace[1419479278] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:258; }","duration":"221.884868ms","start":"2026-03-17T21:51:50.501431Z","end":"2026-03-17T21:51:50.723316Z","steps":["trace[1419479278] 'read index received' (duration: 92.684285ms)","trace[1419479278] 'applied index is now lower than readState.Index' (duration: 129.199343ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:51:50.723431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.131065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-17T21:51:50.723413Z","caller":"traceutil/trace.go:171","msg":"trace[949195310] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"231.054042ms","start":"2026-03-17T21:51:50.492318Z","end":"2026-03-17T21:51:50.723372Z","steps":["trace[949195310] 'process raft request' (duration: 101.66284ms)","trace[949195310] 'compare' (duration: 129.096157ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:50.723466Z","caller":"traceutil/trace.go:171","msg":"trace[1489369117] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:253; }","duration":"231.194028ms","start":"2026-03-17T21:51:50.492259Z","end":"2026-03-17T21:51:50.723453Z","steps":["trace[1489369117] 'agreement among raft nodes before linearized reading' (duration: 231.130495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:50.723505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.986258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-17T21:51:50.72358Z","caller":"traceutil/trace.go:171","msg":"trace[2020917751] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:253; }","duration":"231.097543ms","start":"2026-03-17T21:51:50.492467Z","end":"2026-03-17T21:51:50.723565Z","steps":["trace[2020917751] 'agreement among raft nodes before linearized reading' (duration: 230.903924ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:50.724944Z","caller":"traceutil/trace.go:171","msg":"trace[1428716692] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"222.654366ms","start":"2026-03-17T21:51:50.50225Z","end":"2026-03-17T21:51:50.724905Z","steps":["trace[1428716692] 'process raft request' (duration: 222.457267ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:50.725204Z","caller":"traceutil/trace.go:171","msg":"trace[1141251833] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"220.71912ms","start":"2026-03-17T21:51:50.504466Z","end":"2026-03-17T21:51:50.725186Z","steps":["trace[1141251833] 'process raft request' (duration: 220.344042ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:50.725334Z","caller":"traceutil/trace.go:171","msg":"trace[1680425788] transaction","detail":"{read_only:false; number_of_response:0; response_revision:257; }","duration":"214.379767ms","start":"2026-03-17T21:51:50.510884Z","end":"2026-03-17T21:51:50.725264Z","steps":["trace[1680425788] 'process raft request' (duration: 214.161036ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:50.725421Z","caller":"traceutil/trace.go:171","msg":"trace[1656738126] transaction","detail":"{read_only:false; number_of_response:0; response_revision:257; }","duration":"213.8277ms","start":"2026-03-17T21:51:50.511579Z","end":"2026-03-17T21:51:50.725407Z","steps":["trace[1656738126] 'process raft request' (duration: 213.504103ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:50.725443Z","caller":"traceutil/trace.go:171","msg":"trace[548132734] transaction","detail":"{read_only:false; number_of_response:0; response_revision:255; }","duration":"215.274542ms","start":"2026-03-17T21:51:50.510154Z","end":"2026-03-17T21:51:50.725428Z","steps":["trace[548132734] 'process raft request' (duration: 214.709453ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:50.725539Z","caller":"traceutil/trace.go:171","msg":"trace[2013795945] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"215.25056ms","start":"2026-03-17T21:51:50.51028Z","end":"2026-03-17T21:51:50.725531Z","steps":["trace[2013795945] 'process raft request' (duration: 214.614049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:50.725607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.703329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-03-17T21:51:50.725667Z","caller":"traceutil/trace.go:171","msg":"trace[1883767690] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:257; }","duration":"221.794493ms","start":"2026-03-17T21:51:50.503858Z","end":"2026-03-17T21:51:50.725652Z","steps":["trace[1883767690] 'agreement among raft nodes before linearized reading' (duration: 221.662317ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:51:50.725621Z","caller":"traceutil/trace.go:171","msg":"trace[2095279077] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"215.233739ms","start":"2026-03-17T21:51:50.510379Z","end":"2026-03-17T21:51:50.725613Z","steps":["trace[2095279077] 'process raft request' (duration: 214.569997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:53.39026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.326636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:51:53.390478Z","caller":"traceutil/trace.go:171","msg":"trace[1236978632] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:312; }","duration":"177.342301ms","start":"2026-03-17T21:51:53.213106Z","end":"2026-03-17T21:51:53.390448Z","steps":["trace[1236978632] 'read index received' (duration: 40.617536ms)","trace[1236978632] 'applied index is now lower than readState.Index' (duration: 136.721705ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:53.390573Z","caller":"traceutil/trace.go:171","msg":"trace[1324314136] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"192.66073ms","start":"2026-03-17T21:51:53.197901Z","end":"2026-03-17T21:51:53.390562Z","steps":["trace[1324314136] 'process raft request' (duration: 55.965427ms)","trace[1324314136] 'compare' (duration: 136.20159ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:51:53.39081Z","caller":"traceutil/trace.go:171","msg":"trace[1505283271] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"190.372678ms","start":"2026-03-17T21:51:53.200426Z","end":"2026-03-17T21:51:53.390798Z","steps":["trace[1505283271] 'process raft request' (duration: 189.961377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:53.390914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.811752ms","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-03-17T21:51:53.390973Z","caller":"traceutil/trace.go:171","msg":"trace[1654515588] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:301; }","duration":"128.909907ms","start":"2026-03-17T21:51:53.262048Z","end":"2026-03-17T21:51:53.390958Z","steps":["trace[1654515588] 'agreement among raft nodes before linearized reading' (duration: 128.798072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:53.391264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.14914ms","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-03-17T21:51:53.391312Z","caller":"traceutil/trace.go:171","msg":"trace[495919758] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:301; }","duration":"178.225624ms","start":"2026-03-17T21:51:53.213074Z","end":"2026-03-17T21:51:53.3913Z","steps":["trace[495919758] 'agreement among raft nodes before linearized reading' (duration: 178.13554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:53.813925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.215284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-17T21:51:53.814034Z","caller":"traceutil/trace.go:171","msg":"trace[720781241] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:306; }","duration":"151.377392ms","start":"2026-03-17T21:51:53.662632Z","end":"2026-03-17T21:51:53.81401Z","steps":["trace[720781241] 'range keys from in-memory index tree' (duration: 151.107749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:51:53.814046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.096268ms","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-03-17T21:51:53.814288Z","caller":"traceutil/trace.go:171","msg":"trace[2112036685] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:306; }","duration":"100.370282ms","start":"2026-03-17T21:51:53.71387Z","end":"2026-03-17T21:51:53.81424Z","steps":["trace[2112036685] 'range keys from in-memory index tree' (duration: 99.987632ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:13.23533Z","caller":"traceutil/trace.go:171","msg":"trace[1454551937] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"180.841133ms","start":"2026-03-17T21:52:13.054471Z","end":"2026-03-17T21:52:13.235312Z","steps":["trace[1454551937] 'process raft request' (duration: 129.180213ms)","trace[1454551937] 'compare' (duration: 51.571869ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:52:13.523644Z","caller":"traceutil/trace.go:171","msg":"trace[976861920] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"120.199276ms","start":"2026-03-17T21:52:13.403413Z","end":"2026-03-17T21:52:13.523613Z","steps":["trace[976861920] 'process raft request' (duration: 120.141035ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:13.523917Z","caller":"traceutil/trace.go:171","msg":"trace[562949224] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"120.617079ms","start":"2026-03-17T21:52:13.403283Z","end":"2026-03-17T21:52:13.5239Z","steps":["trace[562949224] 'process raft request' (duration: 69.161772ms)","trace[562949224] 'compare' (duration: 50.806031ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:52:13.705615Z","caller":"traceutil/trace.go:171","msg":"trace[1157409203] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"118.776371ms","start":"2026-03-17T21:52:13.586816Z","end":"2026-03-17T21:52:13.705592Z","steps":["trace[1157409203] 'process raft request' (duration: 118.71162ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:13.705814Z","caller":"traceutil/trace.go:171","msg":"trace[2094488119] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"120.878281ms","start":"2026-03-17T21:52:13.584907Z","end":"2026-03-17T21:52:13.705785Z","steps":["trace[2094488119] 'process raft request' (duration: 43.012863ms)","trace[2094488119] 'compare' (duration: 77.459254ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:52:14.045195Z","caller":"traceutil/trace.go:171","msg":"trace[414128326] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"121.18845ms","start":"2026-03-17T21:52:13.923984Z","end":"2026-03-17T21:52:14.045172Z","steps":["trace[414128326] 'process raft request' (duration: 121.13784ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:14.045297Z","caller":"traceutil/trace.go:171","msg":"trace[1642978611] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"156.217795ms","start":"2026-03-17T21:52:13.889042Z","end":"2026-03-17T21:52:14.04526Z","steps":["trace[1642978611] 'process raft request' (duration: 70.05294ms)","trace[1642978611] 'compare' (duration: 85.842962ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:52:14.782153Z","caller":"traceutil/trace.go:171","msg":"trace[860785493] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"567.815749ms","start":"2026-03-17T21:52:14.214249Z","end":"2026-03-17T21:52:14.782065Z","steps":["trace[860785493] 'process raft request' (duration: 548.263685ms)","trace[860785493] 'compare' (duration: 19.402441ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:52:14.782892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:14.214229Z","time spent":"568.294943ms","remote":"127.0.0.1:57110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:52:14.820703Z","caller":"traceutil/trace.go:171","msg":"trace[542004276] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"604.574792ms","start":"2026-03-17T21:52:14.216101Z","end":"2026-03-17T21:52:14.820676Z","steps":["trace[542004276] 'process raft request' (duration: 604.398499ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:14.821163Z","caller":"traceutil/trace.go:171","msg":"trace[955139901] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"601.127275ms","start":"2026-03-17T21:52:14.220001Z","end":"2026-03-17T21:52:14.821129Z","steps":["trace[955139901] 'process raft request' (duration: 600.669311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:14.821302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:14.219989Z","time spent":"601.243136ms","remote":"127.0.0.1:57378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4339,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:52:14.820887Z","caller":"traceutil/trace.go:171","msg":"trace[916422923] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"600.831142ms","start":"2026-03-17T21:52:14.219987Z","end":"2026-03-17T21:52:14.820818Z","steps":["trace[916422923] 'process raft request' (duration: 600.62893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:14.821423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.373175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-03-17T21:52:14.821509Z","caller":"traceutil/trace.go:171","msg":"trace[2021498673] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:468; }","duration":"499.487346ms","start":"2026-03-17T21:52:14.32199Z","end":"2026-03-17T21:52:14.821477Z","steps":["trace[2021498673] 'agreement among raft nodes before linearized reading' (duration: 498.787719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:14.821568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:14.321975Z","time spent":"499.577317ms","remote":"127.0.0.1:57034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329975,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-03-17T21:52:14.821598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:14.219978Z","time spent":"601.488489ms","remote":"127.0.0.1:57354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4617,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T21:52:14.820737Z","caller":"traceutil/trace.go:171","msg":"trace[1623968199] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:483; }","duration":"498.694308ms","start":"2026-03-17T21:52:14.322022Z","end":"2026-03-17T21:52:14.820716Z","steps":["trace[1623968199] 'read index received' (duration: 440.34946ms)","trace[1623968199] 'applied index is now lower than readState.Index' (duration: 58.343648ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:52:14.821855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.861904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-17T21:52:14.821891Z","caller":"traceutil/trace.go:171","msg":"trace[951404469] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:468; }","duration":"369.927795ms","start":"2026-03-17T21:52:14.451952Z","end":"2026-03-17T21:52:14.82188Z","steps":["trace[951404469] 'agreement among raft nodes before linearized reading' (duration: 369.793983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:14.821923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:14.451935Z","time spent":"369.980835ms","remote":"127.0.0.1:57126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-03-17T21:52:14.822062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.055397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-03-17T21:52:14.822088Z","caller":"traceutil/trace.go:171","msg":"trace[110677698] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:468; }","duration":"370.118178ms","start":"2026-03-17T21:52:14.451964Z","end":"2026-03-17T21:52:14.822082Z","steps":["trace[110677698] 'agreement among raft nodes before linearized reading' (duration: 370.070967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:14.822108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:14.451946Z","time spent":"370.158078ms","remote":"127.0.0.1:57126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-03-17T21:52:14.822258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:14.216078Z","time spent":"605.561982ms","remote":"127.0.0.1:57016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-17T21:52:15.006592Z","caller":"traceutil/trace.go:171","msg":"trace[1231933458] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"161.711289ms","start":"2026-03-17T21:52:14.844851Z","end":"2026-03-17T21:52:15.006563Z","steps":["trace[1231933458] 'process raft request' (duration: 160.201093ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:16.843335Z","caller":"traceutil/trace.go:171","msg":"trace[255242436] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"136.103489ms","start":"2026-03-17T21:52:16.7072Z","end":"2026-03-17T21:52:16.843303Z","steps":["trace[255242436] 'process raft request' (duration: 99.528898ms)","trace[255242436] 'compare' (duration: 36.359949ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:52:16.910258Z","caller":"traceutil/trace.go:171","msg":"trace[420663396] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"194.113967ms","start":"2026-03-17T21:52:16.716123Z","end":"2026-03-17T21:52:16.910237Z","steps":["trace[420663396] 'process raft request' (duration: 193.723193ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:17.557186Z","caller":"traceutil/trace.go:171","msg":"trace[1086250267] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"187.033545ms","start":"2026-03-17T21:52:17.37013Z","end":"2026-03-17T21:52:17.557164Z","steps":["trace[1086250267] 'process raft request' (duration: 186.859564ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:24.052549Z","caller":"traceutil/trace.go:171","msg":"trace[722464437] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"475.911402ms","start":"2026-03-17T21:52:23.576615Z","end":"2026-03-17T21:52:24.052526Z","steps":["trace[722464437] 'read index received' (duration: 475.701379ms)","trace[722464437] 'applied index is now lower than readState.Index' (duration: 209.163µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:52:24.052644Z","caller":"traceutil/trace.go:171","msg":"trace[858905494] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"525.41988ms","start":"2026-03-17T21:52:23.527176Z","end":"2026-03-17T21:52:24.052596Z","steps":["trace[858905494] 'process raft request' (duration: 525.214506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:24.052769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.146336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-17T21:52:24.0528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.19697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:52:24.052848Z","caller":"traceutil/trace.go:171","msg":"trace[1144545302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"142.298182ms","start":"2026-03-17T21:52:23.910537Z","end":"2026-03-17T21:52:24.052835Z","steps":["trace[1144545302] 'agreement among raft nodes before linearized reading' (duration: 142.218601ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:24.052844Z","caller":"traceutil/trace.go:171","msg":"trace[715001853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"476.284288ms","start":"2026-03-17T21:52:23.576545Z","end":"2026-03-17T21:52:24.05283Z","steps":["trace[715001853] 'agreement among raft nodes before linearized reading' (duration: 476.089356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:24.052836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:23.527151Z","time spent":"525.593993ms","remote":"127.0.0.1:57110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T21:52:24.052894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:23.576502Z","time spent":"476.385531ms","remote":"127.0.0.1:57172","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-03-17T21:52:24.053177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.848863ms","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-03-17T21:52:24.053237Z","caller":"traceutil/trace.go:171","msg":"trace[1956289246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"307.946005ms","start":"2026-03-17T21:52:23.74528Z","end":"2026-03-17T21:52:24.053226Z","steps":["trace[1956289246] 'agreement among raft nodes before linearized reading' (duration: 307.377907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:24.053272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:23.745236Z","time spent":"308.027626ms","remote":"127.0.0.1:57172","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-03-17T21:52:24.233769Z","caller":"traceutil/trace.go:171","msg":"trace[1316568699] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"173.209149ms","start":"2026-03-17T21:52:24.060536Z","end":"2026-03-17T21:52:24.233746Z","steps":["trace[1316568699] 'process raft request' (duration: 173.035067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:26.28898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439397105s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T21:52:26.289235Z","caller":"traceutil/trace.go:171","msg":"trace[719584036] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"2.228349033s","start":"2026-03-17T21:52:24.060873Z","end":"2026-03-17T21:52:26.289222Z","steps":["trace[719584036] 'process raft request' (duration: 788.600902ms)","trace[719584036] 'compare' (duration: 1.438996578s)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:52:26.289275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:24.060861Z","time spent":"2.228394383s","remote":"127.0.0.1:57172","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-03-17T21:52:26.289367Z","caller":"traceutil/trace.go:171","msg":"trace[3366604] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"1.371662391s","start":"2026-03-17T21:52:24.917699Z","end":"2026-03-17T21:52:26.289361Z","steps":["trace[3366604] 'read index received' (duration: 55.851µs)","trace[3366604] 'applied index is now lower than readState.Index' (duration: 1.37160589s)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:52:26.289426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371727892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:52:26.289439Z","caller":"traceutil/trace.go:171","msg":"trace[1588485486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"1.371766653s","start":"2026-03-17T21:52:24.917667Z","end":"2026-03-17T21:52:26.289434Z","steps":["trace[1588485486] 'agreement among raft nodes before linearized reading' (duration: 1.371739512s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:26.289452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:24.917652Z","time spent":"1.371797673s","remote":"127.0.0.1:56946","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-03-17T21:52:26.423378Z","caller":"traceutil/trace.go:171","msg":"trace[1978481570] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"133.908372ms","start":"2026-03-17T21:52:26.289377Z","end":"2026-03-17T21:52:26.423285Z","steps":["trace[1978481570] 'read index received' (duration: 127.767361ms)","trace[1978481570] 'applied index is now lower than readState.Index' (duration: 6.139871ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:52:26.423532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.81937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-17T21:52:26.423578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.208641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:52:26.423641Z","caller":"traceutil/trace.go:171","msg":"trace[1366897475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"133.302582ms","start":"2026-03-17T21:52:26.290317Z","end":"2026-03-17T21:52:26.42362Z","steps":["trace[1366897475] 'agreement among raft nodes before linearized reading' (duration: 133.216731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:26.423641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119035406s","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-03-17T21:52:26.423686Z","caller":"traceutil/trace.go:171","msg":"trace[127170111] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"586.838469ms","start":"2026-03-17T21:52:25.836834Z","end":"2026-03-17T21:52:26.423673Z","steps":["trace[127170111] 'process raft request' (duration: 580.328542ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:26.423693Z","caller":"traceutil/trace.go:171","msg":"trace[1882468933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.119135637s","start":"2026-03-17T21:52:25.304543Z","end":"2026-03-17T21:52:26.423679Z","steps":["trace[1882468933] 'agreement among raft nodes before linearized reading' (duration: 1.118945514s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:26.423738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:25.304527Z","time spent":"1.119200488s","remote":"127.0.0.1:57172","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-03-17T21:52:26.42379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:25.836813Z","time spent":"586.9053ms","remote":"127.0.0.1:57172","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-03-17T21:52:26.423636Z","caller":"traceutil/trace.go:171","msg":"trace[1295911916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"185.960662ms","start":"2026-03-17T21:52:26.237652Z","end":"2026-03-17T21:52:26.423612Z","steps":["trace[1295911916] 'agreement among raft nodes before linearized reading' (duration: 185.730649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:26.423896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173648124s","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-03-17T21:52:26.424084Z","caller":"traceutil/trace.go:171","msg":"trace[532208218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"1.173872377s","start":"2026-03-17T21:52:25.250198Z","end":"2026-03-17T21:52:26.42407Z","steps":["trace[532208218] 'agreement among raft nodes before linearized reading' (duration: 1.173604533s)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:52:26.424128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T21:52:25.250179Z","time spent":"1.173936899s","remote":"127.0.0.1:57172","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-03-17T21:52:30.900908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.705655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-03-17T21:52:30.901054Z","caller":"traceutil/trace.go:171","msg":"trace[1908668697] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:594; }","duration":"100.867698ms","start":"2026-03-17T21:52:30.800133Z","end":"2026-03-17T21:52:30.901001Z","steps":["trace[1908668697] 'range keys from in-memory index tree' (duration: 100.51905ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:52:48.827297Z","caller":"traceutil/trace.go:171","msg":"trace[584024454] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"150.825058ms","start":"2026-03-17T21:52:48.676438Z","end":"2026-03-17T21:52:48.827263Z","steps":["trace[584024454] 'process raft request' (duration: 124.822135ms)","trace[584024454] 'compare' (duration: 25.87199ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T21:53:25.06147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.847091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:53:25.061563Z","caller":"traceutil/trace.go:171","msg":"trace[916806523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:838; }","duration":"147.982515ms","start":"2026-03-17T21:53:24.913561Z","end":"2026-03-17T21:53:25.061543Z","steps":["trace[916806523] 'range keys from in-memory index tree' (duration: 147.723826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:53:25.061802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.104169ms","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-03-17T21:53:25.06184Z","caller":"traceutil/trace.go:171","msg":"trace[1965980409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:838; }","duration":"121.169541ms","start":"2026-03-17T21:53:24.940659Z","end":"2026-03-17T21:53:25.061829Z","steps":["trace[1965980409] 'range keys from in-memory index tree' (duration: 121.003106ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:54:25.091166Z","caller":"traceutil/trace.go:171","msg":"trace[709261487] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"130.458043ms","start":"2026-03-17T21:54:24.960674Z","end":"2026-03-17T21:54:25.091132Z","steps":["trace[709261487] 'process raft request' (duration: 130.232624ms)"],"step_count":1}