{"level":"warn","ts":"2026-09-10T01:54:35.480651Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:54:35.480791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.169: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://162.253.55.169:2380","--initial-cluster=instance=https://162.253.55.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.169: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-09-10T01:54:35.480861Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T01:54:35.480941Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.169:2380"]} {"level":"info","ts":"2026-09-10T01:54:35.481097Z","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-09-10T01:54:35.481876Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.169:2379"]} {"level":"info","ts":"2026-09-10T01:54:35.48207Z","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://162.253.55.169:2380"],"listen-peer-urls":["https://162.253.55.169:2380"],"advertise-client-urls":["https://162.253.55.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.169: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-09-10T01:54:35.489242Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.877299ms"} {"level":"info","ts":"2026-09-10T01:54:35.496966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b856fd30c5dc1b8","cluster-id":"9989ba7b656496c6"} {"level":"info","ts":"2026-09-10T01:54:35.497047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T01:54:35.497075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became follower at term 0"} {"level":"info","ts":"2026-09-10T01:54:35.497091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b856fd30c5dc1b8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T01:54:35.497115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became follower at term 1"} {"level":"info","ts":"2026-09-10T01:54:35.497161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 switched to configuration voters=(830192658555978168)"} {"level":"warn","ts":"2026-09-10T01:54:35.502373Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T01:54:35.5038Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T01:54:35.507434Z","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-09-10T01:54:35.509522Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b856fd30c5dc1b8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T01:54:35.509651Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b856fd30c5dc1b8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T01:54:35.509963Z","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-09-10T01:54:35.510119Z","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-09-10T01:54:35.510136Z","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-09-10T01:54:35.512141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 switched to configuration voters=(830192658555978168)"} {"level":"info","ts":"2026-09-10T01:54:35.51227Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9989ba7b656496c6","local-member-id":"b856fd30c5dc1b8","added-peer-id":"b856fd30c5dc1b8","added-peer-peer-urls":["https://162.253.55.169:2380"]} {"level":"info","ts":"2026-09-10T01:54:35.516507Z","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-09-10T01:54:35.516642Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.169:2380"} {"level":"info","ts":"2026-09-10T01:54:35.516661Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.169:2380"} {"level":"info","ts":"2026-09-10T01:54:35.516849Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b856fd30c5dc1b8","initial-advertise-peer-urls":["https://162.253.55.169:2380"],"listen-peer-urls":["https://162.253.55.169:2380"],"advertise-client-urls":["https://162.253.55.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T01:54:35.516906Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T01:54:36.097717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T01:54:36.097809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T01:54:36.097875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 received MsgPreVoteResp from b856fd30c5dc1b8 at term 1"} {"level":"info","ts":"2026-09-10T01:54:36.097898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became candidate at term 2"} {"level":"info","ts":"2026-09-10T01:54:36.097914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 received MsgVoteResp from b856fd30c5dc1b8 at term 2"} {"level":"info","ts":"2026-09-10T01:54:36.097932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b856fd30c5dc1b8 became leader at term 2"} {"level":"info","ts":"2026-09-10T01:54:36.09795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b856fd30c5dc1b8 elected leader b856fd30c5dc1b8 at term 2"} {"level":"info","ts":"2026-09-10T01:54:36.098999Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:54:36.099913Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b856fd30c5dc1b8","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.169:2379]}","request-path":"/0/members/b856fd30c5dc1b8/attributes","cluster-id":"9989ba7b656496c6","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T01:54:36.099919Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:54:36.100003Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T01:54:36.100194Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T01:54:36.100311Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T01:54:36.100574Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9989ba7b656496c6","local-member-id":"b856fd30c5dc1b8","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:54:36.100677Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:54:36.100716Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T01:54:36.103103Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T01:54:36.106402Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.169:2379"} {"level":"info","ts":"2026-09-10T01:54:42.902062Z","caller":"traceutil/trace.go:171","msg":"trace[77085585] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"122.089111ms","start":"2026-09-10T01:54:42.77995Z","end":"2026-09-10T01:54:42.902039Z","steps":["trace[77085585] 'process raft request' (duration: 121.940775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:54:43.342637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.092449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:54:43.342778Z","caller":"traceutil/trace.go:171","msg":"trace[1514077658] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"413.226764ms","start":"2026-09-10T01:54:42.929533Z","end":"2026-09-10T01:54:43.34276Z","steps":["trace[1514077658] 'process raft request' (duration: 206.546835ms)","trace[1514077658] 'compare' (duration: 205.925773ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:54:43.342843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:54:42.929506Z","time spent":"413.311067ms","remote":"127.0.0.1:59450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:55:18.494178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.768525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-09-10T01:55:18.494204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.775565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-09-10T01:55:18.494244Z","caller":"traceutil/trace.go:171","msg":"trace[1146157202] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:536; }","duration":"235.896069ms","start":"2026-09-10T01:55:18.258333Z","end":"2026-09-10T01:55:18.49423Z","steps":["trace[1146157202] 'range keys from in-memory index tree' (duration: 235.681823ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:18.494285Z","caller":"traceutil/trace.go:171","msg":"trace[432051776] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:536; }","duration":"235.894709ms","start":"2026-09-10T01:55:18.25837Z","end":"2026-09-10T01:55:18.494265Z","steps":["trace[432051776] 'range keys from in-memory index tree' (duration: 235.619271ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:20.180264Z","caller":"traceutil/trace.go:171","msg":"trace[79607141] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"125.109015ms","start":"2026-09-10T01:55:20.055133Z","end":"2026-09-10T01:55:20.180242Z","steps":["trace[79607141] 'process raft request' (duration: 108.634923ms)","trace[79607141] 'compare' (duration: 16.244615ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:55:20.180404Z","caller":"traceutil/trace.go:171","msg":"trace[1802821172] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"124.075125ms","start":"2026-09-10T01:55:20.056319Z","end":"2026-09-10T01:55:20.180395Z","steps":["trace[1802821172] 'process raft request' (duration: 123.841458ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:20.180315Z","caller":"traceutil/trace.go:171","msg":"trace[1372356434] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"124.880198ms","start":"2026-09-10T01:55:20.055425Z","end":"2026-09-10T01:55:20.180305Z","steps":["trace[1372356434] 'read index received' (duration: 8.355064ms)","trace[1372356434] 'applied index is now lower than readState.Index' (duration: 116.524524ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:55:20.180577Z","caller":"traceutil/trace.go:171","msg":"trace[1272263093] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"121.335468ms","start":"2026-09-10T01:55:20.059207Z","end":"2026-09-10T01:55:20.180543Z","steps":["trace[1272263093] 'process raft request' (duration: 121.009129ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:20.181703Z","caller":"traceutil/trace.go:171","msg":"trace[476556780] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"104.840597ms","start":"2026-09-10T01:55:20.076849Z","end":"2026-09-10T01:55:20.181689Z","steps":["trace[476556780] 'process raft request' (duration: 103.419737ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:20.182172Z","caller":"traceutil/trace.go:171","msg":"trace[816141541] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"102.303046ms","start":"2026-09-10T01:55:20.079856Z","end":"2026-09-10T01:55:20.182159Z","steps":["trace[816141541] 'process raft request' (duration: 101.749461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:20.182179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.60128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-09-10T01:55:20.182827Z","caller":"traceutil/trace.go:171","msg":"trace[879901256] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"125.29058ms","start":"2026-09-10T01:55:20.057512Z","end":"2026-09-10T01:55:20.182803Z","steps":["trace[879901256] 'agreement among raft nodes before linearized reading' (duration: 124.455587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:20.182265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.402181ms","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-09-10T01:55:20.183082Z","caller":"traceutil/trace.go:171","msg":"trace[1340493962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"127.667376ms","start":"2026-09-10T01:55:20.055402Z","end":"2026-09-10T01:55:20.18307Z","steps":["trace[1340493962] 'agreement among raft nodes before linearized reading' (duration: 125.109214ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:20.182288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.263977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-09-10T01:55:20.183718Z","caller":"traceutil/trace.go:171","msg":"trace[395743360] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"117.706347ms","start":"2026-09-10T01:55:20.065998Z","end":"2026-09-10T01:55:20.183705Z","steps":["trace[395743360] 'agreement among raft nodes before linearized reading' (duration: 116.205255ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:26.23254Z","caller":"traceutil/trace.go:171","msg":"trace[1251896527] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"241.046192ms","start":"2026-09-10T01:55:25.99146Z","end":"2026-09-10T01:55:26.232506Z","steps":["trace[1251896527] 'process raft request' (duration: 240.503967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:26.792449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.710826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T01:55:26.793113Z","caller":"traceutil/trace.go:171","msg":"trace[582870473] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"540.784814ms","start":"2026-09-10T01:55:26.252295Z","end":"2026-09-10T01:55:26.79308Z","steps":["trace[582870473] 'process raft request' (duration: 333.205174ms)","trace[582870473] 'compare' (duration: 206.368507ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:55:26.793205Z","caller":"traceutil/trace.go:171","msg":"trace[2015840379] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"493.888511ms","start":"2026-09-10T01:55:26.299297Z","end":"2026-09-10T01:55:26.793186Z","steps":["trace[2015840379] 'process raft request' (duration: 493.775388ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:26.793199Z","caller":"traceutil/trace.go:171","msg":"trace[728478429] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"537.861946ms","start":"2026-09-10T01:55:26.255306Z","end":"2026-09-10T01:55:26.793168Z","steps":["trace[728478429] 'read index received' (duration: 330.108491ms)","trace[728478429] 'applied index is now lower than readState.Index' (duration: 207.751135ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:55:26.793282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:55:26.252263Z","time spent":"540.930998ms","remote":"127.0.0.1:59532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T01:55:26.793284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:55:26.29925Z","time spent":"493.997884ms","remote":"127.0.0.1:53326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T01:55:26.793469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.883803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T01:55:26.79348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.149463ms","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-09-10T01:55:26.793497Z","caller":"traceutil/trace.go:171","msg":"trace[1413533817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"135.948224ms","start":"2026-09-10T01:55:26.657538Z","end":"2026-09-10T01:55:26.793486Z","steps":["trace[1413533817] 'agreement among raft nodes before linearized reading' (duration: 135.899073ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:55:26.793517Z","caller":"traceutil/trace.go:171","msg":"trace[1695213570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"538.222235ms","start":"2026-09-10T01:55:26.255282Z","end":"2026-09-10T01:55:26.793505Z","steps":["trace[1695213570] 'agreement among raft nodes before linearized reading' (duration: 538.02634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:26.793551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:55:26.255268Z","time spent":"538.274307ms","remote":"127.0.0.1:59532","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":"warn","ts":"2026-09-10T01:55:26.793575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.622914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kqt28\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-10T01:55:26.793647Z","caller":"traceutil/trace.go:171","msg":"trace[1609730199] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kqt28; range_end:; response_count:1; response_revision:649; }","duration":"330.732737ms","start":"2026-09-10T01:55:26.462889Z","end":"2026-09-10T01:55:26.793621Z","steps":["trace[1609730199] 'agreement among raft nodes before linearized reading' (duration: 330.554812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:26.793688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:55:26.46287Z","time spent":"330.806759ms","remote":"127.0.0.1:59444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-kqt28\" "} {"level":"warn","ts":"2026-09-10T01:55:26.793769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.864385ms","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-09-10T01:55:26.793814Z","caller":"traceutil/trace.go:171","msg":"trace[1216683867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"484.933456ms","start":"2026-09-10T01:55:26.308865Z","end":"2026-09-10T01:55:26.793798Z","steps":["trace[1216683867] 'agreement among raft nodes before linearized reading' (duration: 484.492545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:26.79385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:55:26.308852Z","time spent":"484.99038ms","remote":"127.0.0.1:59532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-10T01:55:44.511562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.076066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:55:44.511646Z","caller":"traceutil/trace.go:171","msg":"trace[713359792] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:816; }","duration":"107.258431ms","start":"2026-09-10T01:55:44.404369Z","end":"2026-09-10T01:55:44.511627Z","steps":["trace[713359792] 'range keys from in-memory index tree' (duration: 106.987484ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:44.511808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.85761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:55:44.511842Z","caller":"traceutil/trace.go:171","msg":"trace[450928588] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:816; }","duration":"106.938742ms","start":"2026-09-10T01:55:44.404894Z","end":"2026-09-10T01:55:44.511833Z","steps":["trace[450928588] 'count revisions from in-memory index tree' (duration: 106.654565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:55:44.51208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.66167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:55:44.512133Z","caller":"traceutil/trace.go:171","msg":"trace[1627320415] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:816; }","duration":"105.731272ms","start":"2026-09-10T01:55:44.406382Z","end":"2026-09-10T01:55:44.512113Z","steps":["trace[1627320415] 'range keys from in-memory index tree' (duration: 105.587248ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:14.771104Z","caller":"traceutil/trace.go:171","msg":"trace[454370929] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"111.650693ms","start":"2026-09-10T01:56:14.659405Z","end":"2026-09-10T01:56:14.771055Z","steps":["trace[454370929] 'read index received' (duration: 17.514137ms)","trace[454370929] 'applied index is now lower than readState.Index' (duration: 94.134976ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:56:14.771252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.815907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:56:14.771365Z","caller":"traceutil/trace.go:171","msg":"trace[1036873019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"111.98555ms","start":"2026-09-10T01:56:14.659346Z","end":"2026-09-10T01:56:14.771332Z","steps":["trace[1036873019] 'agreement among raft nodes before linearized reading' (duration: 111.809096ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:56:15.145119Z","caller":"traceutil/trace.go:171","msg":"trace[192793636] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"356.729174ms","start":"2026-09-10T01:56:14.788369Z","end":"2026-09-10T01:56:15.145098Z","steps":["trace[192793636] 'process raft request' (duration: 356.410906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:56:15.145245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:56:14.788343Z","time spent":"356.832266ms","remote":"127.0.0.1:59532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:57:04.606415Z","caller":"traceutil/trace.go:171","msg":"trace[1099681402] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"228.237583ms","start":"2026-09-10T01:57:04.378145Z","end":"2026-09-10T01:57:04.606382Z","steps":["trace[1099681402] 'process raft request' (duration: 228.046349ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:04.688749Z","caller":"traceutil/trace.go:171","msg":"trace[1327882656] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"306.096772ms","start":"2026-09-10T01:57:04.382624Z","end":"2026-09-10T01:57:04.688721Z","steps":["trace[1327882656] 'process raft request' (duration: 305.751054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:04.688906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:57:04.382597Z","time spent":"306.229414ms","remote":"127.0.0.1:59532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:57:04.861209Z","caller":"traceutil/trace.go:171","msg":"trace[1365825410] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"119.666126ms","start":"2026-09-10T01:57:04.74152Z","end":"2026-09-10T01:57:04.861186Z","steps":["trace[1365825410] 'read index received' (duration: 27.439563ms)","trace[1365825410] 'applied index is now lower than readState.Index' (duration: 92.225103ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:57:04.86144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.903481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-10T01:57:04.861474Z","caller":"traceutil/trace.go:171","msg":"trace[1488267686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1930; }","duration":"119.984093ms","start":"2026-09-10T01:57:04.74148Z","end":"2026-09-10T01:57:04.861464Z","steps":["trace[1488267686] 'agreement among raft nodes before linearized reading' (duration: 119.82009ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:16.919798Z","caller":"traceutil/trace.go:171","msg":"trace[670016103] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"180.435097ms","start":"2026-09-10T01:57:16.739315Z","end":"2026-09-10T01:57:16.91975Z","steps":["trace[670016103] 'process raft request' (duration: 179.989468ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:17.538143Z","caller":"traceutil/trace.go:171","msg":"trace[1216102695] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2110; }","duration":"117.863959ms","start":"2026-09-10T01:57:17.420249Z","end":"2026-09-10T01:57:17.538113Z","steps":["trace[1216102695] 'read index received' (duration: 117.761757ms)","trace[1216102695] 'applied index is now lower than readState.Index' (duration: 100.382µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:57:17.538275Z","caller":"traceutil/trace.go:171","msg":"trace[495176252] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"150.09286ms","start":"2026-09-10T01:57:17.388147Z","end":"2026-09-10T01:57:17.538239Z","steps":["trace[495176252] 'process raft request' (duration: 149.753873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:17.538415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.155694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:57:17.538525Z","caller":"traceutil/trace.go:171","msg":"trace[1866359847] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2034; }","duration":"118.339627ms","start":"2026-09-10T01:57:17.420149Z","end":"2026-09-10T01:57:17.538488Z","steps":["trace[1866359847] 'agreement among raft nodes before linearized reading' (duration: 118.137204ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:33.891384Z","caller":"traceutil/trace.go:171","msg":"trace[1664165189] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"263.437461ms","start":"2026-09-10T01:57:33.627915Z","end":"2026-09-10T01:57:33.891353Z","steps":["trace[1664165189] 'process raft request' (duration: 263.267047ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:33.975323Z","caller":"traceutil/trace.go:171","msg":"trace[937398697] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2218; }","duration":"314.561855ms","start":"2026-09-10T01:57:33.660733Z","end":"2026-09-10T01:57:33.975295Z","steps":["trace[937398697] 'read index received' (duration: 230.471886ms)","trace[937398697] 'applied index is now lower than readState.Index' (duration: 84.088299ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:57:33.975587Z","caller":"traceutil/trace.go:171","msg":"trace[1437115218] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"333.049273ms","start":"2026-09-10T01:57:33.642441Z","end":"2026-09-10T01:57:33.97549Z","steps":["trace[1437115218] 'process raft request' (duration: 290.059979ms)","trace[1437115218] 'compare' (duration: 42.584015ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T01:57:33.975714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.377075ms","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-09-10T01:57:33.975779Z","caller":"traceutil/trace.go:171","msg":"trace[1613392867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2140; }","duration":"297.485727ms","start":"2026-09-10T01:57:33.678277Z","end":"2026-09-10T01:57:33.975763Z","steps":["trace[1613392867] 'agreement among raft nodes before linearized reading' (duration: 297.279373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:33.975888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.362638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-10T01:57:33.975976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.234069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:57:33.975975Z","caller":"traceutil/trace.go:171","msg":"trace[121654715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2140; }","duration":"278.512591ms","start":"2026-09-10T01:57:33.697441Z","end":"2026-09-10T01:57:33.975954Z","steps":["trace[121654715] 'agreement among raft nodes before linearized reading' (duration: 278.288737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:33.975913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:57:33.642413Z","time spent":"333.289878ms","remote":"127.0.0.1:59532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T01:57:33.976028Z","caller":"traceutil/trace.go:171","msg":"trace[720499728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2140; }","duration":"315.327531ms","start":"2026-09-10T01:57:33.660685Z","end":"2026-09-10T01:57:33.976013Z","steps":["trace[720499728] 'agreement among raft nodes before linearized reading' (duration: 315.238209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:33.976086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T01:57:33.660664Z","time spent":"315.413252ms","remote":"127.0.0.1:59258","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-09-10T01:57:33.975984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.268785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-10T01:57:33.976147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.045316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T01:57:33.976209Z","caller":"traceutil/trace.go:171","msg":"trace[494313226] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2140; }","duration":"100.170078ms","start":"2026-09-10T01:57:33.876022Z","end":"2026-09-10T01:57:33.976192Z","steps":["trace[494313226] 'agreement among raft nodes before linearized reading' (duration: 99.989695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:33.975937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.950498ms","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-09-10T01:57:33.976399Z","caller":"traceutil/trace.go:171","msg":"trace[1064619490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2140; }","duration":"283.431897ms","start":"2026-09-10T01:57:33.692948Z","end":"2026-09-10T01:57:33.97638Z","steps":["trace[1064619490] 'agreement among raft nodes before linearized reading' (duration: 282.790035ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:33.976204Z","caller":"traceutil/trace.go:171","msg":"trace[2031653926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2140; }","duration":"285.513999ms","start":"2026-09-10T01:57:33.690672Z","end":"2026-09-10T01:57:33.976186Z","steps":["trace[2031653926] 'agreement among raft nodes before linearized reading' (duration: 285.204863ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:40.175014Z","caller":"traceutil/trace.go:171","msg":"trace[2145504443] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"267.689215ms","start":"2026-09-10T01:57:39.9073Z","end":"2026-09-10T01:57:40.17499Z","steps":["trace[2145504443] 'process raft request' (duration: 267.512891ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:40.188707Z","caller":"traceutil/trace.go:171","msg":"trace[1613212500] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2253; }","duration":"167.2478ms","start":"2026-09-10T01:57:40.02143Z","end":"2026-09-10T01:57:40.188678Z","steps":["trace[1613212500] 'read index received' (duration: 153.316745ms)","trace[1613212500] 'applied index is now lower than readState.Index' (duration: 13.929555ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T01:57:40.188858Z","caller":"traceutil/trace.go:171","msg":"trace[1890081718] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"196.741393ms","start":"2026-09-10T01:57:39.992098Z","end":"2026-09-10T01:57:40.18884Z","steps":["trace[1890081718] 'process raft request' (duration: 196.411097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:40.189047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.889638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-10T01:57:40.189047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.306264ms","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-09-10T01:57:40.189108Z","caller":"traceutil/trace.go:171","msg":"trace[806547288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2173; }","duration":"149.98755ms","start":"2026-09-10T01:57:40.039106Z","end":"2026-09-10T01:57:40.189094Z","steps":["trace[806547288] 'agreement among raft nodes before linearized reading' (duration: 149.796496ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:40.189123Z","caller":"traceutil/trace.go:171","msg":"trace[48496527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2173; }","duration":"157.422446ms","start":"2026-09-10T01:57:40.031682Z","end":"2026-09-10T01:57:40.189105Z","steps":["trace[48496527] 'agreement among raft nodes before linearized reading' (duration: 157.191322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:40.189237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.0006ms","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-09-10T01:57:40.189371Z","caller":"traceutil/trace.go:171","msg":"trace[1010884160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"151.145603ms","start":"2026-09-10T01:57:40.038178Z","end":"2026-09-10T01:57:40.189323Z","steps":["trace[1010884160] 'agreement among raft nodes before linearized reading' (duration: 150.792036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T01:57:40.189914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.467375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-10T01:57:40.190053Z","caller":"traceutil/trace.go:171","msg":"trace[1854593655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2173; }","duration":"168.612598ms","start":"2026-09-10T01:57:40.021417Z","end":"2026-09-10T01:57:40.190029Z","steps":["trace[1854593655] 'agreement among raft nodes before linearized reading' (duration: 167.693369ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:40.957946Z","caller":"traceutil/trace.go:171","msg":"trace[547549444] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"162.865585ms","start":"2026-09-10T01:57:40.795055Z","end":"2026-09-10T01:57:40.957921Z","steps":["trace[547549444] 'process raft request' (duration: 162.67828ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T01:57:41.151515Z","caller":"traceutil/trace.go:171","msg":"trace[58114841] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"101.097863ms","start":"2026-09-10T01:57:41.050391Z","end":"2026-09-10T01:57:41.151489Z","steps":["trace[58114841] 'process raft request' (duration: 99.510392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:04:07.233878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.860143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88fd802a-2311-4c01-a043-46a1577a8da3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-10T02:04:07.233945Z","caller":"traceutil/trace.go:171","msg":"trace[435476918] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88fd802a-2311-4c01-a043-46a1577a8da3; range_end:; response_count:1; response_revision:5097; }","duration":"153.942835ms","start":"2026-09-10T02:04:07.079986Z","end":"2026-09-10T02:04:07.233929Z","steps":["trace[435476918] 'range keys from in-memory index tree' (duration: 153.675119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:04:07.234279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.456759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88fd802a-2311-4c01-a043-46a1577a8da3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-10T02:04:07.234383Z","caller":"traceutil/trace.go:171","msg":"trace[644501687] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88fd802a-2311-4c01-a043-46a1577a8da3; range_end:; response_count:1; response_revision:5097; }","duration":"121.682943ms","start":"2026-09-10T02:04:07.112672Z","end":"2026-09-10T02:04:07.234355Z","steps":["trace[644501687] 'range keys from in-memory index tree' (duration: 121.271225ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:07.77861Z","caller":"traceutil/trace.go:171","msg":"trace[306166525] transaction","detail":"{read_only:false; response_revision:5101; number_of_response:1; }","duration":"115.154078ms","start":"2026-09-10T02:04:07.663431Z","end":"2026-09-10T02:04:07.778585Z","steps":["trace[306166525] 'process raft request' (duration: 107.557388ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.402251Z","caller":"traceutil/trace.go:171","msg":"trace[2005284218] linearizableReadLoop","detail":"{readStateIndex:5481; appliedIndex:5480; }","duration":"109.600047ms","start":"2026-09-10T02:04:22.292532Z","end":"2026-09-10T02:04:22.402132Z","steps":["trace[2005284218] 'read index received' (duration: 109.2463ms)","trace[2005284218] 'applied index is now lower than readState.Index' (duration: 350.717µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:04:22.402261Z","caller":"traceutil/trace.go:171","msg":"trace[1961935174] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"167.668589ms","start":"2026-09-10T02:04:22.234554Z","end":"2026-09-10T02:04:22.402223Z","steps":["trace[1961935174] 'process raft request' (duration: 167.297241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:04:22.402419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.873533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-10T02:04:22.402463Z","caller":"traceutil/trace.go:171","msg":"trace[560125389] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5278; }","duration":"109.963375ms","start":"2026-09-10T02:04:22.292488Z","end":"2026-09-10T02:04:22.402452Z","steps":["trace[560125389] 'agreement among raft nodes before linearized reading' (duration: 109.818882ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.724593Z","caller":"traceutil/trace.go:171","msg":"trace[2127550665] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"315.478496ms","start":"2026-09-10T02:04:22.409091Z","end":"2026-09-10T02:04:22.72457Z","steps":["trace[2127550665] 'process raft request' (duration: 289.204602ms)","trace[2127550665] 'compare' (duration: 26.140811ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T02:04:22.724733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:04:22.409074Z","time spent":"315.600468ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:04:22.752865Z","caller":"traceutil/trace.go:171","msg":"trace[441477752] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"343.145352ms","start":"2026-09-10T02:04:22.409691Z","end":"2026-09-10T02:04:22.752836Z","steps":["trace[441477752] 'process raft request' (duration: 342.913247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:04:22.753009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:04:22.40967Z","time spent":"343.261494ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:04:22.753104Z","caller":"traceutil/trace.go:171","msg":"trace[1617299922] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"334.265744ms","start":"2026-09-10T02:04:22.418825Z","end":"2026-09-10T02:04:22.753091Z","steps":["trace[1617299922] 'process raft request' (duration: 334.156132ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.75308Z","caller":"traceutil/trace.go:171","msg":"trace[2099599186] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"339.634115ms","start":"2026-09-10T02:04:22.413414Z","end":"2026-09-10T02:04:22.753049Z","steps":["trace[2099599186] 'process raft request' (duration: 339.392588ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.75287Z","caller":"traceutil/trace.go:171","msg":"trace[1929939390] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"339.435819ms","start":"2026-09-10T02:04:22.413414Z","end":"2026-09-10T02:04:22.75285Z","steps":["trace[1929939390] 'process raft request' (duration: 339.362688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:04:22.753167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:04:22.418811Z","time spent":"334.322956ms","remote":"127.0.0.1:59444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9564,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:04:22.753248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:04:22.4134Z","time spent":"339.775897ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T02:04:22.753245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T02:04:22.413397Z","time spent":"339.754297ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T02:04:22.753461Z","caller":"traceutil/trace.go:171","msg":"trace[768570924] linearizableReadLoop","detail":"{readStateIndex:5486; appliedIndex:5481; }","duration":"295.596064ms","start":"2026-09-10T02:04:22.457853Z","end":"2026-09-10T02:04:22.753449Z","steps":["trace[768570924] 'read index received' (duration: 240.452107ms)","trace[768570924] 'applied index is now lower than readState.Index' (duration: 55.143067ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:04:22.753535Z","caller":"traceutil/trace.go:171","msg":"trace[791046586] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"243.718831ms","start":"2026-09-10T02:04:22.509807Z","end":"2026-09-10T02:04:22.753526Z","steps":["trace[791046586] 'process raft request' (duration: 243.254841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:04:22.753773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.67894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-10T02:04:22.753824Z","caller":"traceutil/trace.go:171","msg":"trace[1348194790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5285; }","duration":"194.759942ms","start":"2026-09-10T02:04:22.559048Z","end":"2026-09-10T02:04:22.753808Z","steps":["trace[1348194790] 'agreement among raft nodes before linearized reading' (duration: 194.562338ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:04:22.753856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.997344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-10T02:04:22.75389Z","caller":"traceutil/trace.go:171","msg":"trace[212852024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5285; }","duration":"296.057384ms","start":"2026-09-10T02:04:22.457824Z","end":"2026-09-10T02:04:22.753881Z","steps":["trace[212852024] 'agreement among raft nodes before linearized reading' (duration: 295.976633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T02:04:22.754025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.221927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T02:04:22.754067Z","caller":"traceutil/trace.go:171","msg":"trace[2078186332] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:5285; }","duration":"153.32362ms","start":"2026-09-10T02:04:22.600728Z","end":"2026-09-10T02:04:22.754052Z","steps":["trace[2078186332] 'agreement among raft nodes before linearized reading' (duration: 153.195257ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.881349Z","caller":"traceutil/trace.go:171","msg":"trace[1427884915] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"121.040891ms","start":"2026-09-10T02:04:22.760254Z","end":"2026-09-10T02:04:22.881295Z","steps":["trace[1427884915] 'process raft request' (duration: 109.549656ms)","trace[1427884915] 'compare' (duration: 11.085216ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:04:22.881826Z","caller":"traceutil/trace.go:171","msg":"trace[755444653] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"119.56086ms","start":"2026-09-10T02:04:22.762228Z","end":"2026-09-10T02:04:22.881789Z","steps":["trace[755444653] 'process raft request' (duration: 119.315243ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.882062Z","caller":"traceutil/trace.go:171","msg":"trace[479791576] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"121.780748ms","start":"2026-09-10T02:04:22.76027Z","end":"2026-09-10T02:04:22.882051Z","steps":["trace[479791576] 'process raft request' (duration: 120.947619ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.882195Z","caller":"traceutil/trace.go:171","msg":"trace[974405] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"117.968764ms","start":"2026-09-10T02:04:22.76419Z","end":"2026-09-10T02:04:22.882158Z","steps":["trace[974405] 'process raft request' (duration: 117.498442ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.882254Z","caller":"traceutil/trace.go:171","msg":"trace[1027005254] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"119.825795ms","start":"2026-09-10T02:04:22.762389Z","end":"2026-09-10T02:04:22.882215Z","steps":["trace[1027005254] 'process raft request' (duration: 119.232641ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:22.882573Z","caller":"traceutil/trace.go:171","msg":"trace[1877735404] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"115.225622ms","start":"2026-09-10T02:04:22.767335Z","end":"2026-09-10T02:04:22.882561Z","steps":["trace[1877735404] 'process raft request' (duration: 114.405124ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T02:04:23.151199Z","caller":"traceutil/trace.go:171","msg":"trace[4699286] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"104.326071ms","start":"2026-09-10T02:04:23.046847Z","end":"2026-09-10T02:04:23.151173Z","steps":["trace[4699286] 'process raft request' (duration: 54.370359ms)","trace[4699286] 'compare' (duration: 49.771928ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T02:04:36.138666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2903} {"level":"info","ts":"2026-09-10T02:04:36.21944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2903,"took":"79.332865ms","hash":3566626089,"current-db-size-bytes":52715520,"current-db-size":"53 MB","current-db-size-in-use-bytes":27770880,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T02:04:36.219513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3566626089,"revision":2903,"compact-revision":-1} {"level":"info","ts":"2026-09-10T02:09:36.145368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5388} {"level":"info","ts":"2026-09-10T02:09:36.225275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5388,"took":"78.406348ms","hash":48190464,"current-db-size-bytes":52715520,"current-db-size":"53 MB","current-db-size-in-use-bytes":19038208,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-10T02:09:36.225325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":48190464,"revision":5388,"compact-revision":2903}