{"level":"warn","ts":"2026-05-20T02:14:26.458601Z","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-20T02:14:26.458885Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-20T02:14:26.459128Z","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-20T02:14:26.459187Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-20T02:14:26.459294Z","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-20T02:14:26.46064Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-05-20T02:14:26.461083Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-20T02:14:26.46545Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.923352ms"} {"level":"info","ts":"2026-05-20T02:14:26.473172Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-05-20T02:14:26.473342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T02:14:26.473424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-05-20T02:14:26.473506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T02:14:26.473551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-05-20T02:14:26.473661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-05-20T02:14:26.480866Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T02:14:26.484222Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T02:14:26.485387Z","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-20T02:14:26.488079Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T02:14:26.488345Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T02:14:26.489281Z","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-20T02:14:26.489369Z","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-20T02:14:26.489394Z","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-20T02:14:26.490156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-05-20T02:14:26.490298Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-05-20T02:14:26.493151Z","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-20T02:14:26.493317Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-20T02:14:26.493371Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-05-20T02:14:26.493687Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T02:14:26.493756Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T02:14:27.374499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T02:14:27.374573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T02:14:27.374598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-05-20T02:14:27.374617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-05-20T02:14:27.374629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-20T02:14:27.374645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-05-20T02:14:27.374661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-05-20T02:14:27.375607Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T02:14:27.37636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T02:14:27.376387Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T02:14:27.376508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T02:14:27.37674Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T02:14:27.376793Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T02:14:27.376864Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T02:14:27.376881Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T02:14:27.376924Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T02:14:27.378925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-05-20T02:14:27.380017Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T02:14:43.180403Z","caller":"traceutil/trace.go:171","msg":"trace[1359334355] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"261.896018ms","start":"2026-05-20T02:14:42.918453Z","end":"2026-05-20T02:14:43.180349Z","steps":["trace[1359334355] 'process raft request' (duration: 260.698011ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:14:43.180472Z","caller":"traceutil/trace.go:171","msg":"trace[1152197290] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"257.905051ms","start":"2026-05-20T02:14:42.921887Z","end":"2026-05-20T02:14:43.179792Z","steps":["trace[1152197290] 'read index received' (duration: 257.174942ms)","trace[1152197290] 'applied index is now lower than readState.Index' (duration: 727.699µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T02:14:43.184122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.303153ms","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-20T02:14:43.184246Z","caller":"traceutil/trace.go:171","msg":"trace[2026297000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"137.492279ms","start":"2026-05-20T02:14:43.046724Z","end":"2026-05-20T02:14:43.184216Z","steps":["trace[2026297000] 'agreement among raft nodes before linearized reading' (duration: 134.716432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:14:43.184083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.02222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T02:14:43.184322Z","caller":"traceutil/trace.go:171","msg":"trace[724198861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"262.450577ms","start":"2026-05-20T02:14:42.921851Z","end":"2026-05-20T02:14:43.184302Z","steps":["trace[724198861] 'agreement among raft nodes before linearized reading' (duration: 258.012416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:14:43.826462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.839419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T02:14:43.826602Z","caller":"traceutil/trace.go:171","msg":"trace[456294075] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"466.102675ms","start":"2026-05-20T02:14:43.360479Z","end":"2026-05-20T02:14:43.826581Z","steps":["trace[456294075] 'read index received' (duration: 91.263628ms)","trace[456294075] 'applied index is now lower than readState.Index' (duration: 374.837547ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T02:14:43.82664Z","caller":"traceutil/trace.go:171","msg":"trace[1323904320] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"637.954358ms","start":"2026-05-20T02:14:43.188651Z","end":"2026-05-20T02:14:43.826605Z","steps":["trace[1323904320] 'process raft request' (duration: 263.1005ms)","trace[1323904320] 'compare' (duration: 372.925494ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T02:14:43.826763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.263522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-05-20T02:14:43.826752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T02:14:43.188638Z","time spent":"638.064964ms","remote":"127.0.0.1:45226","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-20T02:14:43.826807Z","caller":"traceutil/trace.go:171","msg":"trace[1289094780] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:374; }","duration":"466.331834ms","start":"2026-05-20T02:14:43.360465Z","end":"2026-05-20T02:14:43.826797Z","steps":["trace[1289094780] 'agreement among raft nodes before linearized reading' (duration: 466.2231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:14:43.82684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T02:14:43.360405Z","time spent":"466.428629ms","remote":"127.0.0.1:45122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-20T02:14:43.826965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.024443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T02:14:43.827121Z","caller":"traceutil/trace.go:171","msg":"trace[1522181690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"452.229991ms","start":"2026-05-20T02:14:43.374858Z","end":"2026-05-20T02:14:43.827088Z","steps":["trace[1522181690] 'agreement among raft nodes before linearized reading' (duration: 451.987092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:14:43.827318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T02:14:43.374833Z","time spent":"452.473371ms","remote":"127.0.0.1:44922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T02:14:44.335672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960614659763304,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T02:14:44.341734Z","caller":"traceutil/trace.go:171","msg":"trace[2130255548] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"506.422554ms","start":"2026-05-20T02:14:43.835286Z","end":"2026-05-20T02:14:44.341709Z","steps":["trace[2130255548] 'read index received' (duration: 505.646865ms)","trace[2130255548] 'applied index is now lower than readState.Index' (duration: 773.879µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T02:14:44.341972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.659964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T02:14:44.341999Z","caller":"traceutil/trace.go:171","msg":"trace[1178090993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"506.752657ms","start":"2026-05-20T02:14:43.835237Z","end":"2026-05-20T02:14:44.34199Z","steps":["trace[1178090993] 'agreement among raft nodes before linearized reading' (duration: 506.555629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:14:44.342023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T02:14:43.835215Z","time spent":"506.802639ms","remote":"127.0.0.1:44932","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-20T02:14:44.342271Z","caller":"traceutil/trace.go:171","msg":"trace[1633982178] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"506.871432ms","start":"2026-05-20T02:14:43.83515Z","end":"2026-05-20T02:14:44.342021Z","steps":["trace[1633982178] 'process raft request' (duration: 505.951667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:14:44.342453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T02:14:43.835129Z","time spent":"507.216865ms","remote":"127.0.0.1:45122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T02:15:01.589935Z","caller":"traceutil/trace.go:171","msg":"trace[104326894] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"120.39591ms","start":"2026-05-20T02:15:01.469511Z","end":"2026-05-20T02:15:01.589906Z","steps":["trace[104326894] 'process raft request' (duration: 120.193373ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:15:01.605661Z","caller":"traceutil/trace.go:171","msg":"trace[170378469] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"127.625307ms","start":"2026-05-20T02:15:01.478007Z","end":"2026-05-20T02:15:01.605633Z","steps":["trace[170378469] 'process raft request' (duration: 127.42357ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:15:01.608373Z","caller":"traceutil/trace.go:171","msg":"trace[146942933] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"107.766548ms","start":"2026-05-20T02:15:01.50056Z","end":"2026-05-20T02:15:01.608327Z","steps":["trace[146942933] 'read index received' (duration: 90.288176ms)","trace[146942933] 'applied index is now lower than readState.Index' (duration: 17.476722ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T02:15:01.608573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.984705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T02:15:01.608632Z","caller":"traceutil/trace.go:171","msg":"trace[413226333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"108.104588ms","start":"2026-05-20T02:15:01.500512Z","end":"2026-05-20T02:15:01.608616Z","steps":["trace[413226333] 'agreement among raft nodes before linearized reading' (duration: 107.958774ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:15:01.737015Z","caller":"traceutil/trace.go:171","msg":"trace[37881889] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"103.773906ms","start":"2026-05-20T02:15:01.633196Z","end":"2026-05-20T02:15:01.73697Z","steps":["trace[37881889] 'process raft request' (duration: 92.647752ms)","trace[37881889] 'compare' (duration: 10.602517ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T02:15:02.23911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.738728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570096"} {"level":"info","ts":"2026-05-20T02:15:02.239179Z","caller":"traceutil/trace.go:171","msg":"trace[749460831] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:582; }","duration":"173.876993ms","start":"2026-05-20T02:15:02.065286Z","end":"2026-05-20T02:15:02.239163Z","steps":["trace[749460831] 'range keys from in-memory index tree' (duration: 173.366956ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:15:02.24571Z","caller":"traceutil/trace.go:171","msg":"trace[1539239841] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"168.828798ms","start":"2026-05-20T02:15:02.076858Z","end":"2026-05-20T02:15:02.245687Z","steps":["trace[1539239841] 'read index received' (duration: 167.608109ms)","trace[1539239841] 'applied index is now lower than readState.Index' (duration: 1.219569ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T02:15:02.245894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.022195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-05-20T02:15:02.245925Z","caller":"traceutil/trace.go:171","msg":"trace[1878797266] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:583; }","duration":"169.114799ms","start":"2026-05-20T02:15:02.0768Z","end":"2026-05-20T02:15:02.245915Z","steps":["trace[1878797266] 'agreement among raft nodes before linearized reading' (duration: 168.966923ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:15:02.247695Z","caller":"traceutil/trace.go:171","msg":"trace[1894415321] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"171.799335ms","start":"2026-05-20T02:15:02.07584Z","end":"2026-05-20T02:15:02.247639Z","steps":["trace[1894415321] 'process raft request' (duration: 168.980094ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:15:08.097804Z","caller":"traceutil/trace.go:171","msg":"trace[1509958592] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"456.413764ms","start":"2026-05-20T02:15:07.641349Z","end":"2026-05-20T02:15:08.097763Z","steps":["trace[1509958592] 'process raft request' (duration: 455.586698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:15:08.09802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T02:15:07.641313Z","time spent":"456.604039ms","remote":"127.0.0.1:45032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T02:15:08.546249Z","caller":"traceutil/trace.go:171","msg":"trace[1730208083] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"113.194295ms","start":"2026-05-20T02:15:08.433024Z","end":"2026-05-20T02:15:08.546218Z","steps":["trace[1730208083] 'read index received' (duration: 112.908306ms)","trace[1730208083] 'applied index is now lower than readState.Index' (duration: 284.769µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T02:15:08.546282Z","caller":"traceutil/trace.go:171","msg":"trace[13611299] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"368.671114ms","start":"2026-05-20T02:15:08.177577Z","end":"2026-05-20T02:15:08.546248Z","steps":["trace[13611299] 'process raft request' (duration: 368.460246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:15:08.546438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T02:15:08.177543Z","time spent":"368.811448ms","remote":"127.0.0.1:45188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T02:15:08.546613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.558877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dltf6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-20T02:15:08.546662Z","caller":"traceutil/trace.go:171","msg":"trace[1141897531] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dltf6; range_end:; response_count:1; response_revision:640; }","duration":"113.66537ms","start":"2026-05-20T02:15:08.432983Z","end":"2026-05-20T02:15:08.546649Z","steps":["trace[1141897531] 'agreement among raft nodes before linearized reading' (duration: 113.421683ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:15:08.664763Z","caller":"traceutil/trace.go:171","msg":"trace[683815131] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"114.445763ms","start":"2026-05-20T02:15:08.550279Z","end":"2026-05-20T02:15:08.664725Z","steps":["trace[683815131] 'process raft request' (duration: 85.141428ms)","trace[683815131] 'compare' (duration: 28.998876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T02:15:08.665376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.015438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-20T02:15:08.665031Z","caller":"traceutil/trace.go:171","msg":"trace[567100963] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"111.661348ms","start":"2026-05-20T02:15:08.553337Z","end":"2026-05-20T02:15:08.664999Z","steps":["trace[567100963] 'read index received' (duration: 82.099664ms)","trace[567100963] 'applied index is now lower than readState.Index' (duration: 29.559304ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T02:15:08.665456Z","caller":"traceutil/trace.go:171","msg":"trace[152081102] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:642; }","duration":"112.159123ms","start":"2026-05-20T02:15:08.553278Z","end":"2026-05-20T02:15:08.665437Z","steps":["trace[152081102] 'agreement among raft nodes before linearized reading' (duration: 111.846983ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T02:15:08.664766Z","caller":"traceutil/trace.go:171","msg":"trace[1838773120] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"103.895578ms","start":"2026-05-20T02:15:08.560839Z","end":"2026-05-20T02:15:08.664734Z","steps":["trace[1838773120] 'process raft request' (duration: 103.788314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T02:15:24.421658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.725351ms","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-20T02:15:24.421789Z","caller":"traceutil/trace.go:171","msg":"trace[1056748439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"217.957519ms","start":"2026-05-20T02:15:24.203804Z","end":"2026-05-20T02:15:24.421762Z","steps":["trace[1056748439] 'range keys from in-memory index tree' (duration: 217.418023ms)"],"step_count":1}