{"level":"warn","ts":"2026-05-21T02:18:30.442293Z","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-21T02:18:30.442394Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-21T02:18:30.442475Z","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-21T02:18:30.442494Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-21T02:18:30.442519Z","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-21T02:18:30.443387Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-05-21T02:18:30.44348Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-21T02:18:30.488858Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"26.632866ms"} {"level":"info","ts":"2026-05-21T02:18:30.500091Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-05-21T02:18:30.500236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T02:18:30.500271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-05-21T02:18:30.500293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T02:18:30.500363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-05-21T02:18:30.500425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-05-21T02:18:30.506519Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T02:18:30.507753Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T02:18:30.508926Z","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-21T02:18:30.510637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T02:18:30.512546Z","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-21T02:18:30.512683Z","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-21T02:18:30.5127Z","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-21T02:18:30.512806Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T02:18:30.514066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-05-21T02:18:30.51417Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-21T02:18:30.514519Z","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-21T02:18:30.514671Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-21T02:18:30.514708Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-21T02:18:30.514805Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T02:18:30.514852Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T02:18:31.501316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T02:18:31.501387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T02:18:31.501433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-05-21T02:18:31.501518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-05-21T02:18:31.501533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-21T02:18:31.501557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-05-21T02:18:31.501579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-21T02:18:31.502796Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T02:18:31.50283Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T02:18:31.502825Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:18:31.503001Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T02:18:31.503374Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T02:18:31.503413Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T02:18:31.504058Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:18:31.504224Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:18:31.504278Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:18:31.50686Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T02:18:31.506909Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} 2026/05/21 02:18:35 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-05-21T02:18:35.983422Z","caller":"traceutil/trace.go:171","msg":"trace[1342627074] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"135.120998ms","start":"2026-05-21T02:18:35.848285Z","end":"2026-05-21T02:18:35.983406Z","steps":["trace[1342627074] 'process raft request' (duration: 135.006446ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:18:36.215485Z","caller":"traceutil/trace.go:171","msg":"trace[1185362065] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"227.22409ms","start":"2026-05-21T02:18:35.988231Z","end":"2026-05-21T02:18:36.215455Z","steps":["trace[1185362065] 'read index received' (duration: 227.058177ms)","trace[1185362065] 'applied index is now lower than readState.Index' (duration: 164.823µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:18:36.215695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.462415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:18:36.215771Z","caller":"traceutil/trace.go:171","msg":"trace[1546898041] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:276; }","duration":"227.599947ms","start":"2026-05-21T02:18:35.988153Z","end":"2026-05-21T02:18:36.215753Z","steps":["trace[1546898041] 'agreement among raft nodes before linearized reading' (duration: 227.448334ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:18:36.529629Z","caller":"traceutil/trace.go:171","msg":"trace[1094319134] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"457.718115ms","start":"2026-05-21T02:18:36.071867Z","end":"2026-05-21T02:18:36.529585Z","steps":["trace[1094319134] 'process raft request' (duration: 453.227944ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:18:36.529724Z","caller":"traceutil/trace.go:171","msg":"trace[261635917] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"361.435879ms","start":"2026-05-21T02:18:36.168261Z","end":"2026-05-21T02:18:36.529696Z","steps":["trace[261635917] 'process raft request' (duration: 361.229985ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:18:36.529834Z","caller":"traceutil/trace.go:171","msg":"trace[1728256789] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"340.862086ms","start":"2026-05-21T02:18:36.188952Z","end":"2026-05-21T02:18:36.529814Z","steps":["trace[1728256789] 'process raft request' (duration: 340.761514ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:18:36.529792Z","caller":"traceutil/trace.go:171","msg":"trace[1066830642] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:282; }","duration":"314.20266ms","start":"2026-05-21T02:18:36.215569Z","end":"2026-05-21T02:18:36.529771Z","steps":["trace[1066830642] 'read index received' (duration: 309.476424ms)","trace[1066830642] 'applied index is now lower than readState.Index' (duration: 4.723406ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:18:36.529897Z","caller":"traceutil/trace.go:171","msg":"trace[628129252] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"342.011399ms","start":"2026-05-21T02:18:36.187878Z","end":"2026-05-21T02:18:36.52989Z","steps":["trace[628129252] 'process raft request' (duration: 341.778204ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:18:36.529837Z","caller":"traceutil/trace.go:171","msg":"trace[459860040] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"342.509848ms","start":"2026-05-21T02:18:36.187293Z","end":"2026-05-21T02:18:36.529803Z","steps":["trace[459860040] 'process raft request' (duration: 342.239723ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:18:36.529982Z","caller":"traceutil/trace.go:171","msg":"trace[1386685273] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"340.389876ms","start":"2026-05-21T02:18:36.189578Z","end":"2026-05-21T02:18:36.529968Z","steps":["trace[1386685273] 'process raft request' (duration: 340.154711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:18:36.530096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.257107ms","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":"info","ts":"2026-05-21T02:18:36.530138Z","caller":"traceutil/trace.go:171","msg":"trace[1495396657] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:281; }","duration":"540.334018ms","start":"2026-05-21T02:18:35.989791Z","end":"2026-05-21T02:18:36.530125Z","steps":["trace[1495396657] 'agreement among raft nodes before linearized reading' (duration: 540.182605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:18:36.530193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.965704ms","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-21T02:18:36.53028Z","caller":"traceutil/trace.go:171","msg":"trace[1895088381] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:281; }","duration":"312.090016ms","start":"2026-05-21T02:18:36.218174Z","end":"2026-05-21T02:18:36.530264Z","steps":["trace[1895088381] 'agreement among raft nodes before linearized reading' (duration: 311.828201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:18:36.530226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:35.989778Z","time spent":"540.376198ms","remote":"127.0.0.1:53422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-05-21T02:18:36.530323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.561462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-21T02:18:36.530365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.218151Z","time spent":"312.202498ms","remote":"127.0.0.1:53074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-21T02:18:36.530381Z","caller":"traceutil/trace.go:171","msg":"trace[1267977506] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:281; }","duration":"458.641203ms","start":"2026-05-21T02:18:36.071725Z","end":"2026-05-21T02:18:36.530366Z","steps":["trace[1267977506] 'agreement among raft nodes before linearized reading' (duration: 458.545082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:18:36.530427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.071709Z","time spent":"458.703374ms","remote":"127.0.0.1:53364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-21T02:18:36.530529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.071843Z","time spent":"457.869329ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:18:36.529931Z","caller":"traceutil/trace.go:171","msg":"trace[263313268] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"341.829545ms","start":"2026-05-21T02:18:36.188097Z","end":"2026-05-21T02:18:36.529926Z","steps":["trace[263313268] 'process raft request' (duration: 341.591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:18:36.530187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.288197ms","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-21T02:18:36.530662Z","caller":"traceutil/trace.go:171","msg":"trace[991047296] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:281; }","duration":"458.834978ms","start":"2026-05-21T02:18:36.071809Z","end":"2026-05-21T02:18:36.530644Z","steps":["trace[991047296] 'agreement among raft nodes before linearized reading' (duration: 458.275707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:18:36.530773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.168239Z","time spent":"361.555742ms","remote":"127.0.0.1:53136","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:18:36.530812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.071791Z","time spent":"458.96575ms","remote":"127.0.0.1:53232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-21T02:18:36.530701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.188088Z","time spent":"342.530448ms","remote":"127.0.0.1:53146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:18:36.531296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.188941Z","time spent":"340.937407ms","remote":"127.0.0.1:53146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:18:36.532222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.187866Z","time spent":"342.05323ms","remote":"127.0.0.1:53146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:18:36.532363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.187266Z","time spent":"342.677122ms","remote":"127.0.0.1:53146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:18:36.532468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:18:36.189567Z","time spent":"340.482377ms","remote":"127.0.0.1:53146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:19:21.413475Z","caller":"traceutil/trace.go:171","msg":"trace[637042324] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"175.693925ms","start":"2026-05-21T02:19:21.237704Z","end":"2026-05-21T02:19:21.413398Z","steps":["trace[637042324] 'process raft request' (duration: 175.395609ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:19:21.535075Z","caller":"traceutil/trace.go:171","msg":"trace[220591229] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"121.117958ms","start":"2026-05-21T02:19:21.413917Z","end":"2026-05-21T02:19:21.535035Z","steps":["trace[220591229] 'read index received' (duration: 119.948093ms)","trace[220591229] 'applied index is now lower than readState.Index' (duration: 1.168475ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:19:21.535148Z","caller":"traceutil/trace.go:171","msg":"trace[544410058] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"201.341131ms","start":"2026-05-21T02:19:21.33376Z","end":"2026-05-21T02:19:21.535101Z","steps":["trace[544410058] 'process raft request' (duration: 200.447622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:19:21.53543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.66326ms","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-05-21T02:19:21.535517Z","caller":"traceutil/trace.go:171","msg":"trace[620883505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"220.817683ms","start":"2026-05-21T02:19:21.314681Z","end":"2026-05-21T02:19:21.535498Z","steps":["trace[620883505] 'agreement among raft nodes before linearized reading' (duration: 220.475246ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:19:21.549481Z","caller":"traceutil/trace.go:171","msg":"trace[723217304] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"130.772441ms","start":"2026-05-21T02:19:21.418635Z","end":"2026-05-21T02:19:21.549407Z","steps":["trace[723217304] 'process raft request' (duration: 130.576808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:19:22.091022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.351108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:19:22.091257Z","caller":"traceutil/trace.go:171","msg":"trace[1675477353] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"493.817244ms","start":"2026-05-21T02:19:21.597419Z","end":"2026-05-21T02:19:22.091237Z","steps":["trace[1675477353] 'process raft request' (duration: 493.744163ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:19:22.091264Z","caller":"traceutil/trace.go:171","msg":"trace[1566195390] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"514.796757ms","start":"2026-05-21T02:19:21.576436Z","end":"2026-05-21T02:19:22.091233Z","steps":["trace[1566195390] 'process raft request' (duration: 257.822877ms)","trace[1566195390] 'compare' (duration: 256.127424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:19:22.092226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:19:21.57642Z","time spent":"515.671794ms","remote":"127.0.0.1:53232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:19:22.091334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:19:21.597405Z","time spent":"493.901177ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:19:37.816187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.887165ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T02:19:37.81627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.530422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:19:37.81635Z","caller":"traceutil/trace.go:171","msg":"trace[828874893] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:816; }","duration":"152.078369ms","start":"2026-05-21T02:19:37.664249Z","end":"2026-05-21T02:19:37.816328Z","steps":["trace[828874893] 'range keys from in-memory index tree' (duration: 151.869375ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:19:37.816393Z","caller":"traceutil/trace.go:171","msg":"trace[1031343905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"266.733726ms","start":"2026-05-21T02:19:37.549632Z","end":"2026-05-21T02:19:37.816366Z","steps":["trace[1031343905] 'range keys from in-memory index tree' (duration: 266.41775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:19:37.816289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.792477ms","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-05-21T02:19:37.816552Z","caller":"traceutil/trace.go:171","msg":"trace[67770463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"178.107383ms","start":"2026-05-21T02:19:37.638385Z","end":"2026-05-21T02:19:37.816492Z","steps":["trace[67770463] 'range keys from in-memory index tree' (duration: 177.49352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:19:37.816628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.100373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T02:19:37.816753Z","caller":"traceutil/trace.go:171","msg":"trace[819094074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"178.321748ms","start":"2026-05-21T02:19:37.638402Z","end":"2026-05-21T02:19:37.816724Z","steps":["trace[819094074] 'range keys from in-memory index tree' (duration: 177.733515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:19:37.817598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.789577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T02:19:37.81767Z","caller":"traceutil/trace.go:171","msg":"trace[48848415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:816; }","duration":"174.906929ms","start":"2026-05-21T02:19:37.642743Z","end":"2026-05-21T02:19:37.81765Z","steps":["trace[48848415] 'range keys from in-memory index tree' (duration: 173.851449ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:19:37.888358Z","caller":"traceutil/trace.go:171","msg":"trace[184579549] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"165.886907ms","start":"2026-05-21T02:19:37.722444Z","end":"2026-05-21T02:19:37.888331Z","steps":["trace[184579549] 'process raft request' (duration: 165.703023ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:19:37.894286Z","caller":"traceutil/trace.go:171","msg":"trace[876648000] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"112.749877ms","start":"2026-05-21T02:19:37.781503Z","end":"2026-05-21T02:19:37.894253Z","steps":["trace[876648000] 'process raft request' (duration: 112.451492ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:20:38.866302Z","caller":"traceutil/trace.go:171","msg":"trace[1826217700] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"139.414386ms","start":"2026-05-21T02:20:38.726846Z","end":"2026-05-21T02:20:38.866261Z","steps":["trace[1826217700] 'process raft request' (duration: 139.189932ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:20:54.11454Z","caller":"traceutil/trace.go:171","msg":"trace[715119177] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"172.825559ms","start":"2026-05-21T02:20:53.941684Z","end":"2026-05-21T02:20:54.114509Z","steps":["trace[715119177] 'process raft request' (duration: 172.643926ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:21:10.387903Z","caller":"traceutil/trace.go:171","msg":"trace[38422563] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"119.269952ms","start":"2026-05-21T02:21:10.268606Z","end":"2026-05-21T02:21:10.387876Z","steps":["trace[38422563] 'read index received' (duration: 119.054856ms)","trace[38422563] 'applied index is now lower than readState.Index' (duration: 213.806µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:21:10.387962Z","caller":"traceutil/trace.go:171","msg":"trace[1743557493] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"191.875924ms","start":"2026-05-21T02:21:10.196059Z","end":"2026-05-21T02:21:10.387935Z","steps":["trace[1743557493] 'process raft request' (duration: 191.618268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:21:10.388175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.540997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-05-21T02:21:10.388228Z","caller":"traceutil/trace.go:171","msg":"trace[1955322588] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1291; }","duration":"119.618368ms","start":"2026-05-21T02:21:10.268597Z","end":"2026-05-21T02:21:10.388216Z","steps":["trace[1955322588] 'agreement among raft nodes before linearized reading' (duration: 119.417344ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:21:10.583368Z","caller":"traceutil/trace.go:171","msg":"trace[1922057761] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"162.224346ms","start":"2026-05-21T02:21:10.421112Z","end":"2026-05-21T02:21:10.583337Z","steps":["trace[1922057761] 'read index received' (duration: 161.980802ms)","trace[1922057761] 'applied index is now lower than readState.Index' (duration: 242.514µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:21:10.583721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.593483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T02:21:10.583814Z","caller":"traceutil/trace.go:171","msg":"trace[2116791034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"162.692836ms","start":"2026-05-21T02:21:10.421097Z","end":"2026-05-21T02:21:10.58379Z","steps":["trace[2116791034] 'agreement among raft nodes before linearized reading' (duration: 162.449511ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:21:10.583828Z","caller":"traceutil/trace.go:171","msg":"trace[1045524805] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"183.843361ms","start":"2026-05-21T02:21:10.399938Z","end":"2026-05-21T02:21:10.583781Z","steps":["trace[1045524805] 'process raft request' (duration: 183.238199ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:21:24.567498Z","caller":"traceutil/trace.go:171","msg":"trace[1623867969] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"130.777484ms","start":"2026-05-21T02:21:24.43668Z","end":"2026-05-21T02:21:24.567457Z","steps":["trace[1623867969] 'process raft request' (duration: 130.482698ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:21:24.99315Z","caller":"traceutil/trace.go:171","msg":"trace[2138547049] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"251.130118ms","start":"2026-05-21T02:21:24.741987Z","end":"2026-05-21T02:21:24.993118Z","steps":["trace[2138547049] 'read index received' (duration: 248.575767ms)","trace[2138547049] 'applied index is now lower than readState.Index' (duration: 2.552821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:21:24.993529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.509425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T02:21:24.993149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:21:24.672152Z","time spent":"320.985276ms","remote":"127.0.0.1:52982","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T02:21:24.993641Z","caller":"traceutil/trace.go:171","msg":"trace[1019093024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1389; }","duration":"251.687159ms","start":"2026-05-21T02:21:24.741928Z","end":"2026-05-21T02:21:24.993615Z","steps":["trace[1019093024] 'agreement among raft nodes before linearized reading' (duration: 251.298361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:21:24.993818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.678329ms","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-05-21T02:21:24.993879Z","caller":"traceutil/trace.go:171","msg":"trace[1673522440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1389; }","duration":"251.799072ms","start":"2026-05-21T02:21:24.742064Z","end":"2026-05-21T02:21:24.993863Z","steps":["trace[1673522440] 'agreement among raft nodes before linearized reading' (duration: 251.231691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:21:24.994503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.293032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T02:21:24.994563Z","caller":"traceutil/trace.go:171","msg":"trace[94662421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1389; }","duration":"252.350623ms","start":"2026-05-21T02:21:24.742198Z","end":"2026-05-21T02:21:24.994548Z","steps":["trace[94662421] 'agreement among raft nodes before linearized reading' (duration: 252.222081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:21:24.994729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.736463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T02:21:24.99481Z","caller":"traceutil/trace.go:171","msg":"trace[702094905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1389; }","duration":"121.378975ms","start":"2026-05-21T02:21:24.87342Z","end":"2026-05-21T02:21:24.994799Z","steps":["trace[702094905] 'agreement among raft nodes before linearized reading' (duration: 120.555408ms)"],"step_count":1}