{"level":"warn","ts":"2026-05-26T10:29:21.349911Z","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-26T10:29:21.350018Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78: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.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-26T10:29:21.350082Z","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-26T10:29:21.350091Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-26T10:29:21.350109Z","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-26T10:29:21.350661Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-05-26T10:29:21.350724Z","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.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-26T10:29:21.356054Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.124545ms"} {"level":"info","ts":"2026-05-26T10:29:21.364891Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-05-26T10:29:21.364976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T10:29:21.365005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-05-26T10:29:21.365023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T10:29:21.365033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-05-26T10:29:21.365078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-05-26T10:29:21.369431Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T10:29:21.371285Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T10:29:21.374412Z","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-26T10:29:21.376447Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T10:29:21.379064Z","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-26T10:29:21.379451Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T10:29:21.379486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T10:29:21.379671Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T10:29:21.380014Z","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-26T10:29:21.380256Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-26T10:29:21.380355Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-26T10:29:21.380341Z","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-26T10:29:21.38041Z","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-26T10:29:21.381563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-05-26T10:29:21.381705Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-26T10:29:22.365651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T10:29:22.365712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T10:29:22.365758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-05-26T10:29:22.36578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-05-26T10:29:22.365792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-26T10:29:22.365808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-05-26T10:29:22.365825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-26T10:29:22.429767Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T10:29:22.429976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T10:29:22.43006Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T10:29:22.430324Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T10:29:22.430801Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T10:29:22.430874Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T10:29:22.434144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-05-26T10:29:22.434661Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T10:29:22.451501Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T10:29:22.451606Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T10:29:22.451648Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-26T10:29:25.098291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.435293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T10:29:25.098758Z","caller":"traceutil/trace.go:171","msg":"trace[1441734107] linearizableReadLoop","detail":"{readStateIndex:121; appliedIndex:120; }","duration":"430.980618ms","start":"2026-05-26T10:29:24.667763Z","end":"2026-05-26T10:29:25.098744Z","steps":["trace[1441734107] 'read index received' (duration: 262.529224ms)","trace[1441734107] 'applied index is now lower than readState.Index' (duration: 168.450354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T10:29:25.098886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.12167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T10:29:25.098923Z","caller":"traceutil/trace.go:171","msg":"trace[675159037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"431.194832ms","start":"2026-05-26T10:29:24.667717Z","end":"2026-05-26T10:29:25.098912Z","steps":["trace[675159037] 'agreement among raft nodes before linearized reading' (duration: 431.10992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:29:25.098919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.985776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T10:29:25.098897Z","caller":"traceutil/trace.go:171","msg":"trace[434676130] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"442.180415ms","start":"2026-05-26T10:29:24.65663Z","end":"2026-05-26T10:29:25.09881Z","steps":["trace[434676130] 'process raft request' (duration: 273.702051ms)","trace[434676130] 'compare' (duration: 167.235589ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T10:29:25.098994Z","caller":"traceutil/trace.go:171","msg":"trace[443205712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:116; }","duration":"361.095047ms","start":"2026-05-26T10:29:24.737885Z","end":"2026-05-26T10:29:25.09898Z","steps":["trace[443205712] 'agreement among raft nodes before linearized reading' (duration: 360.978135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:29:25.099081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:29:24.737865Z","time spent":"361.20738ms","remote":"127.0.0.1:33500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T10:29:25.098962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:29:24.6677Z","time spent":"431.253393ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T10:29:25.099168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:29:24.656614Z","time spent":"442.425421ms","remote":"127.0.0.1:33848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T10:29:25.369534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.768405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T10:29:25.369671Z","caller":"traceutil/trace.go:171","msg":"trace[2117202973] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"264.977965ms","start":"2026-05-26T10:29:25.104674Z","end":"2026-05-26T10:29:25.369652Z","steps":["trace[2117202973] 'process raft request' (duration: 109.000545ms)","trace[2117202973] 'compare' (duration: 155.586982ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T10:29:58.596285Z","caller":"traceutil/trace.go:171","msg":"trace[1943355350] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"125.100292ms","start":"2026-05-26T10:29:58.471154Z","end":"2026-05-26T10:29:58.596255Z","steps":["trace[1943355350] 'process raft request' (duration: 114.365194ms)","trace[1943355350] 'compare' (duration: 10.575895ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T10:29:58.666565Z","caller":"traceutil/trace.go:171","msg":"trace[1324999972] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"154.918579ms","start":"2026-05-26T10:29:58.511612Z","end":"2026-05-26T10:29:58.666531Z","steps":["trace[1324999972] 'process raft request' (duration: 154.833517ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:29:58.667142Z","caller":"traceutil/trace.go:171","msg":"trace[1212660911] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"167.696127ms","start":"2026-05-26T10:29:58.499422Z","end":"2026-05-26T10:29:58.667118Z","steps":["trace[1212660911] 'process raft request' (duration: 166.82477ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:29:58.968595Z","caller":"traceutil/trace.go:171","msg":"trace[1671518596] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"359.834973ms","start":"2026-05-26T10:29:58.608733Z","end":"2026-05-26T10:29:58.968568Z","steps":["trace[1671518596] 'process raft request' (duration: 294.819121ms)","trace[1671518596] 'compare' (duration: 64.526631ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T10:29:58.968915Z","caller":"traceutil/trace.go:171","msg":"trace[375994450] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"355.842071ms","start":"2026-05-26T10:29:58.613041Z","end":"2026-05-26T10:29:58.968883Z","steps":["trace[375994450] 'process raft request' (duration: 355.451193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:29:58.968974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:29:58.60871Z","time spent":"359.961516ms","remote":"127.0.0.1:33574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T10:29:58.96908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:29:58.613014Z","time spent":"355.968574ms","remote":"127.0.0.1:33574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T10:29:59.005773Z","caller":"traceutil/trace.go:171","msg":"trace[980703357] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"284.406019ms","start":"2026-05-26T10:29:58.721319Z","end":"2026-05-26T10:29:59.005725Z","steps":["trace[980703357] 'process raft request' (duration: 284.129004ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:29:59.005967Z","caller":"traceutil/trace.go:171","msg":"trace[369878146] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:579; }","duration":"133.536274ms","start":"2026-05-26T10:29:58.872408Z","end":"2026-05-26T10:29:59.005944Z","steps":["trace[369878146] 'read index received' (duration: 31.164333ms)","trace[369878146] 'applied index is now lower than readState.Index' (duration: 102.370951ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T10:29:59.006158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.713157ms","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-26T10:29:59.006196Z","caller":"traceutil/trace.go:171","msg":"trace[304401069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"133.81005ms","start":"2026-05-26T10:29:58.872375Z","end":"2026-05-26T10:29:59.006185Z","steps":["trace[304401069] 'agreement among raft nodes before linearized reading' (duration: 133.651096ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:29:59.006177Z","caller":"traceutil/trace.go:171","msg":"trace[920924342] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"283.44767ms","start":"2026-05-26T10:29:58.722708Z","end":"2026-05-26T10:29:59.006155Z","steps":["trace[920924342] 'process raft request' (duration: 282.92473ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:30:04.475123Z","caller":"traceutil/trace.go:171","msg":"trace[1382661530] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"152.246813ms","start":"2026-05-26T10:30:04.322841Z","end":"2026-05-26T10:30:04.475087Z","steps":["trace[1382661530] 'process raft request' (duration: 151.693272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:30:05.065492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.786795ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T10:30:05.065655Z","caller":"traceutil/trace.go:171","msg":"trace[1547331793] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:619; }","duration":"280.981259ms","start":"2026-05-26T10:30:04.784641Z","end":"2026-05-26T10:30:05.065623Z","steps":["trace[1547331793] 'range keys from in-memory index tree' (duration: 280.767695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:30:05.066232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.370914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T10:30:05.067067Z","caller":"traceutil/trace.go:171","msg":"trace[509174200] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"409.809648ms","start":"2026-05-26T10:30:04.657225Z","end":"2026-05-26T10:30:05.067035Z","steps":["trace[509174200] 'process raft request' (duration: 231.512564ms)","trace[509174200] 'compare' (duration: 176.974056ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T10:30:05.067197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:30:04.657195Z","time spent":"409.92474ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T10:30:05.067227Z","caller":"traceutil/trace.go:171","msg":"trace[778554015] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"218.810176ms","start":"2026-05-26T10:30:04.848387Z","end":"2026-05-26T10:30:05.067197Z","steps":["trace[778554015] 'read index received' (duration: 40.291328ms)","trace[778554015] 'applied index is now lower than readState.Index' (duration: 178.515458ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T10:30:05.067429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.032341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h992z\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-26T10:30:05.067668Z","caller":"traceutil/trace.go:171","msg":"trace[1779257179] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h992z; range_end:; response_count:1; response_revision:620; }","duration":"219.106722ms","start":"2026-05-26T10:30:04.848366Z","end":"2026-05-26T10:30:05.067473Z","steps":["trace[1779257179] 'agreement among raft nodes before linearized reading' (duration: 218.904778ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:31:02.869811Z","caller":"traceutil/trace.go:171","msg":"trace[1212090598] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"347.145877ms","start":"2026-05-26T10:31:02.522647Z","end":"2026-05-26T10:31:02.869793Z","steps":["trace[1212090598] 'process raft request' (duration: 347.039455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:31:02.86994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:31:02.522619Z","time spent":"347.262339ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T10:31:02.873635Z","caller":"traceutil/trace.go:171","msg":"trace[1515454769] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"349.258131ms","start":"2026-05-26T10:31:02.524364Z","end":"2026-05-26T10:31:02.873623Z","steps":["trace[1515454769] 'process raft request' (duration: 348.802432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:31:02.873694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:31:02.524348Z","time spent":"349.316071ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T10:31:02.983137Z","caller":"traceutil/trace.go:171","msg":"trace[143251581] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"106.205663ms","start":"2026-05-26T10:31:02.876914Z","end":"2026-05-26T10:31:02.98312Z","steps":["trace[143251581] 'process raft request' (duration: 104.379236ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:31:02.983322Z","caller":"traceutil/trace.go:171","msg":"trace[1777585349] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"105.53786ms","start":"2026-05-26T10:31:02.877754Z","end":"2026-05-26T10:31:02.983292Z","steps":["trace[1777585349] 'process raft request' (duration: 105.334996ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:31:25.359227Z","caller":"traceutil/trace.go:171","msg":"trace[1691003594] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"102.767889ms","start":"2026-05-26T10:31:25.256428Z","end":"2026-05-26T10:31:25.359195Z","steps":["trace[1691003594] 'read index received' (duration: 102.622396ms)","trace[1691003594] 'applied index is now lower than readState.Index' (duration: 144.553µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T10:31:25.35936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.922972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T10:31:25.359441Z","caller":"traceutil/trace.go:171","msg":"trace[1803488983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1105; }","duration":"103.043944ms","start":"2026-05-26T10:31:25.256374Z","end":"2026-05-26T10:31:25.359417Z","steps":["trace[1803488983] 'agreement among raft nodes before linearized reading' (duration: 102.927392ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T10:31:25.359275Z","caller":"traceutil/trace.go:171","msg":"trace[944863374] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"129.381629ms","start":"2026-05-26T10:31:25.229862Z","end":"2026-05-26T10:31:25.359243Z","steps":["trace[944863374] 'process raft request' (duration: 129.207505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:31:41.560426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.530458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T10:31:41.560573Z","caller":"traceutil/trace.go:171","msg":"trace[1596869167] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"419.860129ms","start":"2026-05-26T10:31:41.140686Z","end":"2026-05-26T10:31:41.560546Z","steps":["trace[1596869167] 'process raft request' (duration: 266.137037ms)","trace[1596869167] 'compare' (duration: 153.423606ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T10:31:41.560668Z","caller":"traceutil/trace.go:171","msg":"trace[1039868028] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"417.746676ms","start":"2026-05-26T10:31:41.142892Z","end":"2026-05-26T10:31:41.560638Z","steps":["trace[1039868028] 'read index received' (duration: 263.972243ms)","trace[1039868028] 'applied index is now lower than readState.Index' (duration: 153.772423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T10:31:41.560705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:31:41.140669Z","time spent":"419.964801ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T10:31:41.560708Z","caller":"traceutil/trace.go:171","msg":"trace[2108480331] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"139.615626ms","start":"2026-05-26T10:31:41.42107Z","end":"2026-05-26T10:31:41.560686Z","steps":["trace[2108480331] 'process raft request' (duration: 139.459203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:31:41.562256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.985064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T10:31:41.562303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.409389ms","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-26T10:31:41.562334Z","caller":"traceutil/trace.go:171","msg":"trace[1544749952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"305.123577ms","start":"2026-05-26T10:31:41.257197Z","end":"2026-05-26T10:31:41.56232Z","steps":["trace[1544749952] 'agreement among raft nodes before linearized reading' (duration: 305.024735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:31:41.562412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:31:41.257166Z","time spent":"305.236788ms","remote":"127.0.0.1:33494","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-26T10:31:41.562339Z","caller":"traceutil/trace.go:171","msg":"trace[1684528056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"419.464041ms","start":"2026-05-26T10:31:41.142864Z","end":"2026-05-26T10:31:41.562328Z","steps":["trace[1684528056] 'agreement among raft nodes before linearized reading' (duration: 417.881189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:31:41.562477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T10:31:41.142856Z","time spent":"419.611234ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T10:32:09.812501Z","caller":"traceutil/trace.go:171","msg":"trace[1643266088] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"133.515483ms","start":"2026-05-26T10:32:09.678952Z","end":"2026-05-26T10:32:09.812468Z","steps":["trace[1643266088] 'process raft request' (duration: 88.354009ms)","trace[1643266088] 'compare' (duration: 44.912689ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T10:32:09.81286Z","caller":"traceutil/trace.go:171","msg":"trace[562050893] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1426; }","duration":"102.304862ms","start":"2026-05-26T10:32:09.710503Z","end":"2026-05-26T10:32:09.812808Z","steps":["trace[562050893] 'read index received' (duration: 56.73996ms)","trace[562050893] 'applied index is now lower than readState.Index' (duration: 45.562562ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T10:32:09.812936Z","caller":"traceutil/trace.go:171","msg":"trace[389957723] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"133.230977ms","start":"2026-05-26T10:32:09.67968Z","end":"2026-05-26T10:32:09.812911Z","steps":["trace[389957723] 'process raft request' (duration: 132.725527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T10:32:09.813159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.630858ms","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-26T10:32:09.813263Z","caller":"traceutil/trace.go:171","msg":"trace[1406407431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"102.747751ms","start":"2026-05-26T10:32:09.710458Z","end":"2026-05-26T10:32:09.813206Z","steps":["trace[1406407431] 'agreement among raft nodes before linearized reading' (duration: 102.461415ms)"],"step_count":1}