{"level":"warn","ts":"2026-05-16T09:16:00.358275Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-16T09:16:00.358367Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-05-16T09:16:00.358432Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-16T09:16:00.358447Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-16T09:16:00.358463Z","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-05-16T09:16:00.359084Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-16T09:16:00.359151Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-05-16T09:16:00.363681Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.322768ms"} {"level":"info","ts":"2026-05-16T09:16:00.372667Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-16T09:16:00.372742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-16T09:16:00.37277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-16T09:16:00.372787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-16T09:16:00.372802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-16T09:16:00.372851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-16T09:16:00.38004Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-16T09:16:00.381811Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-16T09:16:00.383839Z","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-05-16T09:16:00.385918Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-16T09:16:00.386209Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-16T09:16:00.386657Z","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-05-16T09:16:00.386789Z","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-05-16T09:16:00.386799Z","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-05-16T09:16:00.387954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-16T09:16:00.388139Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-16T09:16:00.391031Z","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-05-16T09:16:00.391093Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-16T09:16:00.391147Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-16T09:16:00.392633Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-16T09:16:00.392668Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-16T09:16:01.274016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-16T09:16:01.274086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-16T09:16:01.27412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-16T09:16:01.27414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-16T09:16:01.274151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-16T09:16:01.27417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-16T09:16:01.274187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-16T09:16:01.27561Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-16T09:16:01.275647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-16T09:16:01.275809Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-16T09:16:01.275901Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-16T09:16:01.275957Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-16T09:16:01.276082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-16T09:16:01.276588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-16T09:16:01.276692Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-16T09:16:01.276733Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-16T09:16:01.278257Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-16T09:16:01.281481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"warn","ts":"2026-05-16T09:16:06.770236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.956272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-16T09:16:06.770352Z","caller":"traceutil/trace.go:171","msg":"trace[1587327155] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:279; }","duration":"396.121744ms","start":"2026-05-16T09:16:06.374212Z","end":"2026-05-16T09:16:06.770333Z","steps":["trace[1587327155] 'range keys from in-memory index tree' (duration: 395.873822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:06.770409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.374196Z","time spent":"396.201156ms","remote":"127.0.0.1:48124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-16T09:16:06.770537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.508619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-16T09:16:06.770635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.618237ms","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-05-16T09:16:06.770669Z","caller":"traceutil/trace.go:171","msg":"trace[1902023507] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:279; }","duration":"313.681457ms","start":"2026-05-16T09:16:06.456977Z","end":"2026-05-16T09:16:06.770658Z","steps":["trace[1902023507] 'range keys from in-memory index tree' (duration: 313.567427ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:06.770662Z","caller":"traceutil/trace.go:171","msg":"trace[1734938648] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:279; }","duration":"395.675561ms","start":"2026-05-16T09:16:06.374957Z","end":"2026-05-16T09:16:06.770632Z","steps":["trace[1734938648] 'range keys from in-memory index tree' (duration: 395.432029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:06.770699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.456962Z","time spent":"313.731347ms","remote":"127.0.0.1:48266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-16T09:16:06.770716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.374939Z","time spent":"395.763171ms","remote":"127.0.0.1:48474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-05-16T09:16:06.770973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.852368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-16T09:16:06.771031Z","caller":"traceutil/trace.go:171","msg":"trace[917368277] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:279; }","duration":"313.935549ms","start":"2026-05-16T09:16:06.457082Z","end":"2026-05-16T09:16:06.771018Z","steps":["trace[917368277] 'range keys from in-memory index tree' (duration: 313.792798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:06.771062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.457068Z","time spent":"313.984809ms","remote":"127.0.0.1:48400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-16T09:16:06.771331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.959712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-16T09:16:06.771376Z","caller":"traceutil/trace.go:171","msg":"trace[2003915616] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"314.051361ms","start":"2026-05-16T09:16:06.457316Z","end":"2026-05-16T09:16:06.771368Z","steps":["trace[2003915616] 'process raft request' (duration: 157.719456ms)","trace[2003915616] 'compare' (duration: 155.355287ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T09:16:06.771419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.457303Z","time spent":"314.092471ms","remote":"127.0.0.1:48092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-16T09:16:07.016291Z","caller":"traceutil/trace.go:171","msg":"trace[2134316253] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:286; }","duration":"253.987667ms","start":"2026-05-16T09:16:06.762276Z","end":"2026-05-16T09:16:07.016263Z","steps":["trace[2134316253] 'read index received' (duration: 229.218693ms)","trace[2134316253] 'applied index is now lower than readState.Index' (duration: 24.767944ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T09:16:07.016405Z","caller":"traceutil/trace.go:171","msg":"trace[1858477332] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"448.013661ms","start":"2026-05-16T09:16:06.568361Z","end":"2026-05-16T09:16:07.016375Z","steps":["trace[1858477332] 'process raft request' (duration: 447.730519ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:07.01648Z","caller":"traceutil/trace.go:171","msg":"trace[709586091] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"447.390455ms","start":"2026-05-16T09:16:06.569078Z","end":"2026-05-16T09:16:07.016468Z","steps":["trace[709586091] 'process raft request' (duration: 447.115353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:07.016551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.568338Z","time spent":"448.141482ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-16T09:16:07.016567Z","caller":"traceutil/trace.go:171","msg":"trace[146924128] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"470.482875ms","start":"2026-05-16T09:16:06.546069Z","end":"2026-05-16T09:16:07.016552Z","steps":["trace[146924128] 'process raft request' (duration: 445.411019ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:07.016591Z","caller":"traceutil/trace.go:171","msg":"trace[1009367636] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"448.0307ms","start":"2026-05-16T09:16:06.568554Z","end":"2026-05-16T09:16:07.016585Z","steps":["trace[1009367636] 'process raft request' (duration: 447.602427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:07.016621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.568536Z","time spent":"448.073231ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-16T09:16:07.016641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.546046Z","time spent":"470.562806ms","remote":"127.0.0.1:48184","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-16T09:16:07.016514Z","caller":"traceutil/trace.go:171","msg":"trace[1718529635] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"446.037564ms","start":"2026-05-16T09:16:06.57046Z","end":"2026-05-16T09:16:07.016497Z","steps":["trace[1718529635] 'process raft request' (duration: 445.767282ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:07.016714Z","caller":"traceutil/trace.go:171","msg":"trace[761346391] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"449.996317ms","start":"2026-05-16T09:16:06.566702Z","end":"2026-05-16T09:16:07.016698Z","steps":["trace[761346391] 'process raft request' (duration: 449.286762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:07.016763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.566681Z","time spent":"450.057648ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-16T09:16:07.016562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.569064Z","time spent":"447.447756ms","remote":"127.0.0.1:48190","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-05-16T09:16:07.016952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:06.57045Z","time spent":"446.289226ms","remote":"127.0.0.1:48190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-16T09:16:07.017087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.788933ms","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-05-16T09:16:07.017166Z","caller":"traceutil/trace.go:171","msg":"trace[1838652015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:285; }","duration":"254.921384ms","start":"2026-05-16T09:16:06.762227Z","end":"2026-05-16T09:16:07.017148Z","steps":["trace[1838652015] 'agreement among raft nodes before linearized reading' (duration: 254.730882ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:07.033376Z","caller":"traceutil/trace.go:171","msg":"trace[306646484] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"258.013738ms","start":"2026-05-16T09:16:06.775344Z","end":"2026-05-16T09:16:07.033358Z","steps":["trace[306646484] 'process raft request' (duration: 257.872597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:07.033468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.158845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T09:16:07.03353Z","caller":"traceutil/trace.go:171","msg":"trace[707492899] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:287; }","duration":"255.243465ms","start":"2026-05-16T09:16:06.778273Z","end":"2026-05-16T09:16:07.033516Z","steps":["trace[707492899] 'agreement among raft nodes before linearized reading' (duration: 255.109674ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:07.03359Z","caller":"traceutil/trace.go:171","msg":"trace[1528204796] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"257.813067ms","start":"2026-05-16T09:16:06.775755Z","end":"2026-05-16T09:16:07.033568Z","steps":["trace[1528204796] 'process raft request' (duration: 257.537245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:07.033468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.733045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-16T09:16:07.033683Z","caller":"traceutil/trace.go:171","msg":"trace[290272578] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:287; }","duration":"258.913136ms","start":"2026-05-16T09:16:06.77472Z","end":"2026-05-16T09:16:07.033633Z","steps":["trace[290272578] 'agreement among raft nodes before linearized reading' (duration: 258.669154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:07.03381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.150639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-16T09:16:07.033906Z","caller":"traceutil/trace.go:171","msg":"trace[910146342] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:287; }","duration":"259.287819ms","start":"2026-05-16T09:16:06.774599Z","end":"2026-05-16T09:16:07.033887Z","steps":["trace[910146342] 'agreement among raft nodes before linearized reading' (duration: 258.935617ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:10.733375Z","caller":"traceutil/trace.go:171","msg":"trace[1423103790] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"109.126671ms","start":"2026-05-16T09:16:10.624225Z","end":"2026-05-16T09:16:10.733351Z","steps":["trace[1423103790] 'read index received' (duration: 90.657853ms)","trace[1423103790] 'applied index is now lower than readState.Index' (duration: 18.467928ms)"],"step_count":2} {"level":"info","ts":"2026-05-16T09:16:10.733516Z","caller":"traceutil/trace.go:171","msg":"trace[652611329] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"139.430023ms","start":"2026-05-16T09:16:10.59406Z","end":"2026-05-16T09:16:10.73349Z","steps":["trace[652611329] 'process raft request' (duration: 120.872385ms)","trace[652611329] 'compare' (duration: 18.289716ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T09:16:10.733668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.402893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-16T09:16:10.733739Z","caller":"traceutil/trace.go:171","msg":"trace[365624272] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:363; }","duration":"109.552294ms","start":"2026-05-16T09:16:10.624171Z","end":"2026-05-16T09:16:10.733723Z","steps":["trace[365624272] 'agreement among raft nodes before linearized reading' (duration: 109.348353ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:31.934362Z","caller":"traceutil/trace.go:171","msg":"trace[2055867770] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"161.461753ms","start":"2026-05-16T09:16:31.772879Z","end":"2026-05-16T09:16:31.934341Z","steps":["trace[2055867770] 'process raft request' (duration: 161.350822ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:38.2104Z","caller":"traceutil/trace.go:171","msg":"trace[1080957976] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"255.939666ms","start":"2026-05-16T09:16:37.954438Z","end":"2026-05-16T09:16:38.210377Z","steps":["trace[1080957976] 'read index received' (duration: 253.698569ms)","trace[1080957976] 'applied index is now lower than readState.Index' (duration: 2.240357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-16T09:16:38.210528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.075923ms","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-05-16T09:16:38.210561Z","caller":"traceutil/trace.go:171","msg":"trace[1768420260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"256.161966ms","start":"2026-05-16T09:16:37.954388Z","end":"2026-05-16T09:16:38.21055Z","steps":["trace[1768420260] 'agreement among raft nodes before linearized reading' (duration: 256.081092ms)"],"step_count":1} {"level":"info","ts":"2026-05-16T09:16:38.210506Z","caller":"traceutil/trace.go:171","msg":"trace[1948924810] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"365.723665ms","start":"2026-05-16T09:16:37.844752Z","end":"2026-05-16T09:16:38.210476Z","steps":["trace[1948924810] 'process raft request' (duration: 364.025562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:38.210959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:37.844731Z","time spent":"365.945745ms","remote":"127.0.0.1:48110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-16T09:16:38.622091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.684768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-16T09:16:38.622184Z","caller":"traceutil/trace.go:171","msg":"trace[962505947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"367.809553ms","start":"2026-05-16T09:16:38.25435Z","end":"2026-05-16T09:16:38.622159Z","steps":["trace[962505947] 'range keys from in-memory index tree' (duration: 367.603404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:38.622227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:38.254335Z","time spent":"367.881347ms","remote":"127.0.0.1:48010","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-05-16T09:16:38.698529Z","caller":"traceutil/trace.go:171","msg":"trace[812571377] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"424.936411ms","start":"2026-05-16T09:16:38.273574Z","end":"2026-05-16T09:16:38.69851Z","steps":["trace[812571377] 'process raft request' (duration: 424.777384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-16T09:16:38.698648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-16T09:16:38.273556Z","time spent":"425.036225ms","remote":"127.0.0.1:48208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-16T09:16:54.434502Z","caller":"traceutil/trace.go:171","msg":"trace[2108100108] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"133.63514ms","start":"2026-05-16T09:16:54.300829Z","end":"2026-05-16T09:16:54.434464Z","steps":["trace[2108100108] 'process raft request' (duration: 133.437833ms)"],"step_count":1}