{"level":"warn","ts":"2026-06-16T00:33:51.411934Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T00:33:51.412021Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.30: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.30:2380","--initial-cluster=instance=https://199.19.213.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-16T00:33:51.412094Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-16T00:33:51.412105Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-06-16T00:33:51.412124Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-16T00:33:51.412903Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"]} {"level":"info","ts":"2026-06-16T00:33:51.412976Z","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.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-16T00:33:51.414704Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.530066ms"} {"level":"info","ts":"2026-06-16T00:33:51.421238Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2b5783e11defe7e","cluster-id":"5210a9231a779bf0"} {"level":"info","ts":"2026-06-16T00:33:51.421352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=()"} {"level":"info","ts":"2026-06-16T00:33:51.421377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 0"} {"level":"info","ts":"2026-06-16T00:33:51.421393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2b5783e11defe7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-16T00:33:51.421403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 1"} {"level":"info","ts":"2026-06-16T00:33:51.421448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"warn","ts":"2026-06-16T00:33:51.428044Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-16T00:33:51.431555Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-16T00:33:51.432669Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-16T00:33:51.435658Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2b5783e11defe7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-16T00:33:51.436839Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2b5783e11defe7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-16T00:33:51.436955Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:33:51.437552Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:33:51.437692Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-16T00:33:51.43853Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-16T00:33:51.438748Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2b5783e11defe7e","initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-16T00:33:51.438782Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-16T00:33:51.438891Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-06-16T00:33:51.438905Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-06-16T00:33:51.442326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"info","ts":"2026-06-16T00:33:51.442434Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","added-peer-id":"e2b5783e11defe7e","added-peer-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-06-16T00:33:52.321682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e is starting a new election at term 1"} {"level":"info","ts":"2026-06-16T00:33:52.321739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became pre-candidate at term 1"} {"level":"info","ts":"2026-06-16T00:33:52.321773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgPreVoteResp from e2b5783e11defe7e at term 1"} {"level":"info","ts":"2026-06-16T00:33:52.32179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became candidate at term 2"} {"level":"info","ts":"2026-06-16T00:33:52.321797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgVoteResp from e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-06-16T00:33:52.321819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became leader at term 2"} {"level":"info","ts":"2026-06-16T00:33:52.321831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2b5783e11defe7e elected leader e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-06-16T00:33:52.322543Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:33:52.323324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2b5783e11defe7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.30:2379]}","request-path":"/0/members/e2b5783e11defe7e/attributes","cluster-id":"5210a9231a779bf0","publish-timeout":"7s"} {"level":"info","ts":"2026-06-16T00:33:52.32372Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:33:52.323787Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-16T00:33:52.323916Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-16T00:33:52.324066Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:33:52.324098Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-16T00:33:52.324181Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:33:52.324214Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-16T00:33:52.326265Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-16T00:33:52.327984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.30:2379"} {"level":"warn","ts":"2026-06-16T00:33:55.494282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.818804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:33:55.494472Z","caller":"traceutil/trace.go:171","msg":"trace[543404568] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"328.866762ms","start":"2026-06-16T00:33:55.165582Z","end":"2026-06-16T00:33:55.494449Z","steps":["trace[543404568] 'process raft request' (duration: 135.261709ms)","trace[543404568] 'compare' (duration: 192.540088ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:33:55.494552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:55.165563Z","time spent":"328.965335ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-16T00:33:55.857384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.711122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:33:55.857869Z","caller":"traceutil/trace.go:171","msg":"trace[685804318] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"355.852992ms","start":"2026-06-16T00:33:55.501948Z","end":"2026-06-16T00:33:55.857801Z","steps":["trace[685804318] 'process raft request' (duration: 137.629908ms)","trace[685804318] 'compare' (duration: 217.527807ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:33:55.858112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:33:55.50192Z","time spent":"356.081827ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:34:37.14537Z","caller":"traceutil/trace.go:171","msg":"trace[1536983412] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"157.837436ms","start":"2026-06-16T00:34:36.987499Z","end":"2026-06-16T00:34:37.145337Z","steps":["trace[1536983412] 'process raft request' (duration: 157.625031ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:34:37.495234Z","caller":"traceutil/trace.go:171","msg":"trace[1612778807] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"197.195755ms","start":"2026-06-16T00:34:37.298017Z","end":"2026-06-16T00:34:37.495213Z","steps":["trace[1612778807] 'process raft request' (duration: 196.678363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:38.05672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.137837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:34:38.057016Z","caller":"traceutil/trace.go:171","msg":"trace[977500551] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"506.560649ms","start":"2026-06-16T00:34:37.550412Z","end":"2026-06-16T00:34:38.056973Z","steps":["trace[977500551] 'process raft request' (duration: 286.034612ms)","trace[977500551] 'compare' (duration: 219.914152ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:38.057206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:37.550398Z","time spent":"506.704773ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-16T00:34:38.057784Z","caller":"traceutil/trace.go:171","msg":"trace[958912461] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"315.894838ms","start":"2026-06-16T00:34:37.741854Z","end":"2026-06-16T00:34:38.057749Z","steps":["trace[958912461] 'read index received' (duration: 94.608614ms)","trace[958912461] 'applied index is now lower than readState.Index' (duration: 221.283684ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:34:38.058397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.502012ms","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-06-16T00:34:38.058454Z","caller":"traceutil/trace.go:171","msg":"trace[537561789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"316.616834ms","start":"2026-06-16T00:34:37.741815Z","end":"2026-06-16T00:34:38.058432Z","steps":["trace[537561789] 'agreement among raft nodes before linearized reading' (duration: 316.223575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:34:38.058521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:34:37.741796Z","time spent":"316.710056ms","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-16T00:34:53.317673Z","caller":"traceutil/trace.go:171","msg":"trace[1164980387] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"242.138166ms","start":"2026-06-16T00:34:53.075503Z","end":"2026-06-16T00:34:53.317641Z","steps":["trace[1164980387] 'process raft request' (duration: 241.676715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:35:28.634952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.395058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T00:35:28.635096Z","caller":"traceutil/trace.go:171","msg":"trace[447100577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"116.610393ms","start":"2026-06-16T00:35:28.518451Z","end":"2026-06-16T00:35:28.635061Z","steps":["trace[447100577] 'range keys from in-memory index tree' (duration: 116.152132ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:28.771861Z","caller":"traceutil/trace.go:171","msg":"trace[1375954471] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"101.308302ms","start":"2026-06-16T00:35:28.67052Z","end":"2026-06-16T00:35:28.771828Z","steps":["trace[1375954471] 'read index received' (duration: 101.020795ms)","trace[1375954471] 'applied index is now lower than readState.Index' (duration: 285.697µs)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:35:28.771964Z","caller":"traceutil/trace.go:171","msg":"trace[1525449213] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"131.281088ms","start":"2026-06-16T00:35:28.64064Z","end":"2026-06-16T00:35:28.771921Z","steps":["trace[1525449213] 'process raft request' (duration: 130.972151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:35:28.772139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.592969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-16T00:35:28.772199Z","caller":"traceutil/trace.go:171","msg":"trace[947420450] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1017; }","duration":"101.682551ms","start":"2026-06-16T00:35:28.670502Z","end":"2026-06-16T00:35:28.772184Z","steps":["trace[947420450] 'agreement among raft nodes before linearized reading' (duration: 101.504806ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:35:56.661143Z","caller":"traceutil/trace.go:171","msg":"trace[1502258840] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"211.903601ms","start":"2026-06-16T00:35:56.449203Z","end":"2026-06-16T00:35:56.661107Z","steps":["trace[1502258840] 'process raft request' (duration: 183.905004ms)","trace[1502258840] 'compare' (duration: 27.862794ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:35:56.665351Z","caller":"traceutil/trace.go:171","msg":"trace[1387704190] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"215.049921ms","start":"2026-06-16T00:35:56.450274Z","end":"2026-06-16T00:35:56.665324Z","steps":["trace[1387704190] 'process raft request' (duration: 214.901878ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:30.386255Z","caller":"traceutil/trace.go:171","msg":"trace[798241093] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"273.87978ms","start":"2026-06-16T00:36:30.112354Z","end":"2026-06-16T00:36:30.386234Z","steps":["trace[798241093] 'process raft request' (duration: 273.721566ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:30.938601Z","caller":"traceutil/trace.go:171","msg":"trace[71788095] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"111.301152ms","start":"2026-06-16T00:36:30.827275Z","end":"2026-06-16T00:36:30.938576Z","steps":["trace[71788095] 'process raft request' (duration: 111.151078ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:31.073076Z","caller":"traceutil/trace.go:171","msg":"trace[1767630667] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1343; }","duration":"140.441665ms","start":"2026-06-16T00:36:30.932609Z","end":"2026-06-16T00:36:31.073051Z","steps":["trace[1767630667] 'read index received' (duration: 6.101839ms)","trace[1767630667] 'applied index is now lower than readState.Index' (duration: 134.336866ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:36:31.073106Z","caller":"traceutil/trace.go:171","msg":"trace[1253208962] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"243.97925ms","start":"2026-06-16T00:36:30.829091Z","end":"2026-06-16T00:36:31.073071Z","steps":["trace[1253208962] 'process raft request' (duration: 243.768205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:31.073233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.596398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:36:31.073275Z","caller":"traceutil/trace.go:171","msg":"trace[1825694747] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1294; }","duration":"140.69608ms","start":"2026-06-16T00:36:30.932565Z","end":"2026-06-16T00:36:31.073261Z","steps":["trace[1825694747] 'agreement among raft nodes before linearized reading' (duration: 140.597238ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:31.073712Z","caller":"traceutil/trace.go:171","msg":"trace[1734215722] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"243.661443ms","start":"2026-06-16T00:36:30.830039Z","end":"2026-06-16T00:36:31.073701Z","steps":["trace[1734215722] 'process raft request' (duration: 242.954977ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:31.252754Z","caller":"traceutil/trace.go:171","msg":"trace[1315710736] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"157.39542ms","start":"2026-06-16T00:36:31.095332Z","end":"2026-06-16T00:36:31.252728Z","steps":["trace[1315710736] 'process raft request' (duration: 114.015404ms)","trace[1315710736] 'compare' (duration: 43.219603ms)"],"step_count":2} {"level":"warn","ts":"2026-06-16T00:36:31.575627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.7888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-16T00:36:31.57577Z","caller":"traceutil/trace.go:171","msg":"trace[29635473] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"127.820257ms","start":"2026-06-16T00:36:31.447913Z","end":"2026-06-16T00:36:31.575733Z","steps":["trace[29635473] 'compare' (duration: 117.613915ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:31.725882Z","caller":"traceutil/trace.go:171","msg":"trace[188376176] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"145.280365ms","start":"2026-06-16T00:36:31.580577Z","end":"2026-06-16T00:36:31.725858Z","steps":["trace[188376176] 'process raft request' (duration: 130.376906ms)","trace[188376176] 'compare' (duration: 14.775216ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:36:31.863095Z","caller":"traceutil/trace.go:171","msg":"trace[1945726337] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"106.512793ms","start":"2026-06-16T00:36:31.75654Z","end":"2026-06-16T00:36:31.863053Z","steps":["trace[1945726337] 'process raft request' (duration: 79.462087ms)","trace[1945726337] 'compare' (duration: 25.576692ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:36:31.863625Z","caller":"traceutil/trace.go:171","msg":"trace[390345516] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"106.851101ms","start":"2026-06-16T00:36:31.756692Z","end":"2026-06-16T00:36:31.863543Z","steps":["trace[390345516] 'process raft request' (duration: 106.061503ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:36:41.583121Z","caller":"traceutil/trace.go:171","msg":"trace[1605846285] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"132.30309ms","start":"2026-06-16T00:36:41.45078Z","end":"2026-06-16T00:36:41.583084Z","steps":["trace[1605846285] 'process raft request' (duration: 132.067094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:36:41.960948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.999037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-16T00:36:41.961068Z","caller":"traceutil/trace.go:171","msg":"trace[1121519251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1373; }","duration":"244.183001ms","start":"2026-06-16T00:36:41.716856Z","end":"2026-06-16T00:36:41.961039Z","steps":["trace[1121519251] 'range keys from in-memory index tree' (duration: 243.876465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:22.217165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338269339650576305,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T00:39:22.718829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338269339650576305,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-16T00:39:23.135842Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.790879147s","expected-duration":"1s"} {"level":"info","ts":"2026-06-16T00:39:23.182754Z","caller":"traceutil/trace.go:171","msg":"trace[821185133] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2190; }","duration":"1.466513013s","start":"2026-06-16T00:39:21.716221Z","end":"2026-06-16T00:39:23.182734Z","steps":["trace[821185133] 'read index received' (duration: 1.419890852s)","trace[821185133] 'applied index is now lower than readState.Index' (duration: 46.621321ms)"],"step_count":2} {"level":"info","ts":"2026-06-16T00:39:23.18287Z","caller":"traceutil/trace.go:171","msg":"trace[1764250667] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"1.021452995s","start":"2026-06-16T00:39:22.16141Z","end":"2026-06-16T00:39:23.182863Z","steps":["trace[1764250667] 'process raft request' (duration: 1.021142438s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:23.183256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.679717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-06-16T00:39:23.183309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:22.161388Z","time spent":"1.021500126s","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-16T00:39:23.183358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.467143528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-16T00:39:23.183425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.397028881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-16T00:39:23.183438Z","caller":"traceutil/trace.go:171","msg":"trace[238165753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2106; }","duration":"1.46724826s","start":"2026-06-16T00:39:21.716158Z","end":"2026-06-16T00:39:23.183406Z","steps":["trace[238165753] 'agreement among raft nodes before linearized reading' (duration: 1.467167338s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:23.183456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.886711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-16T00:39:23.18349Z","caller":"traceutil/trace.go:171","msg":"trace[440653303] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2106; }","duration":"862.919041ms","start":"2026-06-16T00:39:22.320562Z","end":"2026-06-16T00:39:23.183481Z","steps":["trace[440653303] 'agreement among raft nodes before linearized reading' (duration: 862.8297ms)"],"step_count":1} {"level":"info","ts":"2026-06-16T00:39:23.18349Z","caller":"traceutil/trace.go:171","msg":"trace[1737809949] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2106; }","duration":"1.397090473s","start":"2026-06-16T00:39:21.786369Z","end":"2026-06-16T00:39:23.183459Z","steps":["trace[1737809949] 'agreement among raft nodes before linearized reading' (duration: 1.397013891s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:23.183484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292561723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-16T00:39:23.183533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:22.320502Z","time spent":"863.023254ms","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-16T00:39:23.183495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:21.716136Z","time spent":"1.467350182s","remote":"127.0.0.1:54434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-16T00:39:23.183546Z","caller":"traceutil/trace.go:171","msg":"trace[1823677383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2106; }","duration":"1.292660236s","start":"2026-06-16T00:39:21.890871Z","end":"2026-06-16T00:39:23.183531Z","steps":["trace[1823677383] 'agreement among raft nodes before linearized reading' (duration: 1.292496262s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:23.183366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112123511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-16T00:39:23.183661Z","caller":"traceutil/trace.go:171","msg":"trace[1931312740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2106; }","duration":"1.112414107s","start":"2026-06-16T00:39:22.071237Z","end":"2026-06-16T00:39:23.183651Z","steps":["trace[1931312740] 'agreement among raft nodes before linearized reading' (duration: 1.11207998s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:23.183698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:22.071129Z","time spent":"1.11256098s","remote":"127.0.0.1:54710","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":"info","ts":"2026-06-16T00:39:23.18336Z","caller":"traceutil/trace.go:171","msg":"trace[656194735] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2106; }","duration":"862.83154ms","start":"2026-06-16T00:39:22.320501Z","end":"2026-06-16T00:39:23.183332Z","steps":["trace[656194735] 'agreement among raft nodes before linearized reading' (duration: 862.510663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:23.183688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:21.890847Z","time spent":"1.292781689s","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-16T00:39:23.183775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:22.320481Z","time spent":"863.28196ms","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-16T00:39:23.183261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399883074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-16T00:39:23.183265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.818201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-16T00:39:23.183877Z","caller":"traceutil/trace.go:171","msg":"trace[1507859085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2106; }","duration":"1.400579789s","start":"2026-06-16T00:39:21.783287Z","end":"2026-06-16T00:39:23.183867Z","steps":["trace[1507859085] 'agreement among raft nodes before linearized reading' (duration: 1.39971847s)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:23.183903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:21.783262Z","time spent":"1.40063404s","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-16T00:39:23.183916Z","caller":"traceutil/trace.go:171","msg":"trace[686760313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2106; }","duration":"422.558606ms","start":"2026-06-16T00:39:22.761334Z","end":"2026-06-16T00:39:23.183893Z","steps":["trace[686760313] 'agreement among raft nodes before linearized reading' (duration: 421.740079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-16T00:39:23.183957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:22.761307Z","time spent":"422.639689ms","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-16T00:39:23.183543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-16T00:39:21.786358Z","time spent":"1.397177435s","remote":"127.0.0.1:54710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}