{"level":"warn","ts":"2026-02-12T03:35:45.617889Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T03:35:45.618069Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99: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.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99: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-02-12T03:35:45.618188Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T03:35:45.618233Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-12T03:35:45.618275Z","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-02-12T03:35:45.619039Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-02-12T03:35:45.619154Z","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":2,"max-cpu-available":2,"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.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99: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-02-12T03:35:45.624673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.285037ms"} {"level":"info","ts":"2026-02-12T03:35:45.643823Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-02-12T03:35:45.643933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T03:35:45.643961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-02-12T03:35:45.643991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T03:35:45.644013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-02-12T03:35:45.644049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-02-12T03:35:45.657559Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T03:35:45.66105Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T03:35:45.664035Z","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-02-12T03:35:45.670922Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T03:35:45.671908Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T03:35:45.673144Z","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-02-12T03:35:45.673199Z","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-02-12T03:35:45.673222Z","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-02-12T03:35:45.673405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-02-12T03:35:45.673531Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-12T03:35:45.687302Z","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-02-12T03:35:45.687523Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T03:35:45.687568Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T03:35:45.687672Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-12T03:35:45.687702Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-12T03:35:46.045361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T03:35:46.045484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T03:35:46.045646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-02-12T03:35:46.046024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-02-12T03:35:46.046082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-12T03:35:46.046127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-02-12T03:35:46.04616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-12T03:35:46.258246Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:35:46.266234Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T03:35:46.266406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:35:46.266677Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T03:35:46.266731Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T03:35:46.266815Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:35:46.268452Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-02-12T03:35:46.27225Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T03:35:46.28745Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:35:46.288084Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:35:46.288122Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-12T03:35:58.12478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.027252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4012"} {"level":"info","ts":"2026-02-12T03:35:58.124903Z","caller":"traceutil/trace.go:171","msg":"trace[1320024801] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:353; }","duration":"155.189962ms","start":"2026-02-12T03:35:57.969694Z","end":"2026-02-12T03:35:58.124884Z","steps":["trace[1320024801] 'range keys from in-memory index tree' (duration: 154.921551ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:04.427472Z","caller":"traceutil/trace.go:171","msg":"trace[1438758411] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"252.895697ms","start":"2026-02-12T03:36:04.17456Z","end":"2026-02-12T03:36:04.427455Z","steps":["trace[1438758411] 'read index received' (duration: 252.822527ms)","trace[1438758411] 'applied index is now lower than readState.Index' (duration: 72.46µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:36:04.427565Z","caller":"traceutil/trace.go:171","msg":"trace[300761025] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"256.161027ms","start":"2026-02-12T03:36:04.171398Z","end":"2026-02-12T03:36:04.42756Z","steps":["trace[300761025] 'process raft request' (duration: 255.957286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:04.427847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.792674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-12T03:36:04.427974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.302186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:04.427993Z","caller":"traceutil/trace.go:171","msg":"trace[1890147501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"143.349286ms","start":"2026-02-12T03:36:04.284636Z","end":"2026-02-12T03:36:04.427985Z","steps":["trace[1890147501] 'agreement among raft nodes before linearized reading' (duration: 143.327426ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:04.427987Z","caller":"traceutil/trace.go:171","msg":"trace[464508609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"188.923075ms","start":"2026-02-12T03:36:04.239008Z","end":"2026-02-12T03:36:04.427931Z","steps":["trace[464508609] 'agreement among raft nodes before linearized reading' (duration: 188.744444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:04.428106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.146933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T03:36:04.428121Z","caller":"traceutil/trace.go:171","msg":"trace[1360432735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"185.201743ms","start":"2026-02-12T03:36:04.242915Z","end":"2026-02-12T03:36:04.428117Z","steps":["trace[1360432735] 'agreement among raft nodes before linearized reading' (duration: 185.167572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:04.42791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.34118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-12T03:36:04.428177Z","caller":"traceutil/trace.go:171","msg":"trace[1725878753] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:406; }","duration":"253.634802ms","start":"2026-02-12T03:36:04.17453Z","end":"2026-02-12T03:36:04.428165Z","steps":["trace[1725878753] 'agreement among raft nodes before linearized reading' (duration: 253.34683ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:06.537668Z","caller":"traceutil/trace.go:171","msg":"trace[778732756] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"101.014996ms","start":"2026-02-12T03:36:06.436632Z","end":"2026-02-12T03:36:06.537647Z","steps":["trace[778732756] 'process raft request' (duration: 100.700203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:18.931311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.941182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:18.931394Z","caller":"traceutil/trace.go:171","msg":"trace[1546308679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"105.060302ms","start":"2026-02-12T03:36:18.826319Z","end":"2026-02-12T03:36:18.93138Z","steps":["trace[1546308679] 'range keys from in-memory index tree' (duration: 104.867932ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:24.296578Z","caller":"traceutil/trace.go:171","msg":"trace[1448630735] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"566.575558ms","start":"2026-02-12T03:36:23.72999Z","end":"2026-02-12T03:36:24.296565Z","steps":["trace[1448630735] 'process raft request' (duration: 566.301827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:24.296904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:23.729965Z","time spent":"566.651548ms","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:36:25.275506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114749535711344,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:36:25.775889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114749535711344,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:36:26.672818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.609597ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T03:36:26.672883Z","caller":"traceutil/trace.go:171","msg":"trace[951970837] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"1.897780889s","start":"2026-02-12T03:36:24.775093Z","end":"2026-02-12T03:36:26.672874Z","steps":["trace[951970837] 'read index received' (duration: 1.157132231s)","trace[951970837] 'applied index is now lower than readState.Index' (duration: 740.647828ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:26.673021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.897921789s","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-02-12T03:36:26.673033Z","caller":"traceutil/trace.go:171","msg":"trace[153113810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"1.897962421s","start":"2026-02-12T03:36:24.775067Z","end":"2026-02-12T03:36:26.673029Z","steps":["trace[153113810] 'agreement among raft nodes before linearized reading' (duration: 1.8978666s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:26.673053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:24.775054Z","time spent":"1.897994031s","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T03:36:26.673215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.866375231s","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-02-12T03:36:26.673227Z","caller":"traceutil/trace.go:171","msg":"trace[199270289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"1.866387101s","start":"2026-02-12T03:36:24.806836Z","end":"2026-02-12T03:36:26.673223Z","steps":["trace[199270289] 'agreement among raft nodes before linearized reading' (duration: 1.866351381s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:26.673238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:24.806797Z","time spent":"1.866438581s","remote":"127.0.0.1:53652","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-02-12T03:36:26.673323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.438623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:26.673335Z","caller":"traceutil/trace.go:171","msg":"trace[678081728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"388.475763ms","start":"2026-02-12T03:36:26.284855Z","end":"2026-02-12T03:36:26.673331Z","steps":["trace[678081728] 'agreement among raft nodes before linearized reading' (duration: 388.456883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:26.673359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:26.284841Z","time spent":"388.502324ms","remote":"127.0.0.1:53368","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-02-12T03:36:26.673446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854944424s","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-02-12T03:36:26.673458Z","caller":"traceutil/trace.go:171","msg":"trace[884853436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"1.854965524s","start":"2026-02-12T03:36:24.818488Z","end":"2026-02-12T03:36:26.673454Z","steps":["trace[884853436] 'agreement among raft nodes before linearized reading' (duration: 1.854931004s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:26.673468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:24.818484Z","time spent":"1.854982344s","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:36:26.673538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.855531367s","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-02-12T03:36:26.673549Z","caller":"traceutil/trace.go:171","msg":"trace[943000673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"1.855578567s","start":"2026-02-12T03:36:24.817967Z","end":"2026-02-12T03:36:26.673545Z","steps":["trace[943000673] 'agreement among raft nodes before linearized reading' (duration: 1.855547187s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:26.673564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:24.817954Z","time spent":"1.855602967s","remote":"127.0.0.1:53652","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-02-12T03:36:31.207017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.641155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:36:31.207674Z","caller":"traceutil/trace.go:171","msg":"trace[1476192240] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"153.850185ms","start":"2026-02-12T03:36:31.053806Z","end":"2026-02-12T03:36:31.207656Z","steps":["trace[1476192240] 'read index received' (duration: 30.513036ms)","trace[1476192240] 'applied index is now lower than readState.Index' (duration: 123.336059ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:31.207841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.032186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-12T03:36:31.207879Z","caller":"traceutil/trace.go:171","msg":"trace[894918410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:583; }","duration":"154.100757ms","start":"2026-02-12T03:36:31.053767Z","end":"2026-02-12T03:36:31.207868Z","steps":["trace[894918410] 'agreement among raft nodes before linearized reading' (duration: 153.945856ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:31.208509Z","caller":"traceutil/trace.go:171","msg":"trace[149128659] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"242.974857ms","start":"2026-02-12T03:36:30.965521Z","end":"2026-02-12T03:36:31.208496Z","steps":["trace[149128659] 'process raft request' (duration: 118.790625ms)","trace[149128659] 'compare' (duration: 122.437034ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:36:31.208643Z","caller":"traceutil/trace.go:171","msg":"trace[1897052466] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"169.899483ms","start":"2026-02-12T03:36:31.038734Z","end":"2026-02-12T03:36:31.208633Z","steps":["trace[1897052466] 'process raft request' (duration: 168.552696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:40.008385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.614042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:36:40.00848Z","caller":"traceutil/trace.go:171","msg":"trace[1239789248] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"429.28415ms","start":"2026-02-12T03:36:39.579179Z","end":"2026-02-12T03:36:40.008463Z","steps":["trace[1239789248] 'read index received' (duration: 22.483751ms)","trace[1239789248] 'applied index is now lower than readState.Index' (duration: 406.799449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:40.008907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.734838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T03:36:40.00895Z","caller":"traceutil/trace.go:171","msg":"trace[1989522565] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:688; }","duration":"429.849292ms","start":"2026-02-12T03:36:39.579089Z","end":"2026-02-12T03:36:40.008939Z","steps":["trace[1989522565] 'agreement among raft nodes before linearized reading' (duration: 429.641954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:40.009018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:39.579066Z","time spent":"429.940545ms","remote":"127.0.0.1:53390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.99\" "} {"level":"info","ts":"2026-02-12T03:36:40.009476Z","caller":"traceutil/trace.go:171","msg":"trace[2086739822] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"705.953538ms","start":"2026-02-12T03:36:39.303508Z","end":"2026-02-12T03:36:40.009462Z","steps":["trace[2086739822] 'process raft request' (duration: 298.194584ms)","trace[2086739822] 'compare' (duration: 406.061342ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:40.009549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:39.303479Z","time spent":"706.034451ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:36:40.356965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.213286ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-12T03:36:40.35719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:40.011556Z","time spent":"345.631672ms","remote":"127.0.0.1:53390","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T03:36:40.771158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.078046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:36:40.771258Z","caller":"traceutil/trace.go:171","msg":"trace[574098069] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"413.139319ms","start":"2026-02-12T03:36:40.358093Z","end":"2026-02-12T03:36:40.771232Z","steps":["trace[574098069] 'read index received' (duration: 268.875177ms)","trace[574098069] 'applied index is now lower than readState.Index' (duration: 144.262682ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:36:40.77135Z","caller":"traceutil/trace.go:171","msg":"trace[395841522] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"413.45571ms","start":"2026-02-12T03:36:40.357881Z","end":"2026-02-12T03:36:40.771337Z","steps":["trace[395841522] 'process raft request' (duration: 269.129945ms)","trace[395841522] 'compare' (duration: 143.91014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:40.7714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:40.357868Z","time spent":"413.504522ms","remote":"127.0.0.1:53390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:36:40.771568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.46449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:40.77161Z","caller":"traceutil/trace.go:171","msg":"trace[1833914481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"413.547003ms","start":"2026-02-12T03:36:40.358052Z","end":"2026-02-12T03:36:40.771599Z","steps":["trace[1833914481] 'agreement among raft nodes before linearized reading' (duration: 413.489991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:40.771629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:40.358037Z","time spent":"413.588455ms","remote":"127.0.0.1:53376","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-02-12T03:36:40.98728Z","caller":"traceutil/trace.go:171","msg":"trace[1566901305] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"209.312579ms","start":"2026-02-12T03:36:40.777954Z","end":"2026-02-12T03:36:40.987266Z","steps":["trace[1566901305] 'process raft request' (duration: 209.003618ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:40.987577Z","caller":"traceutil/trace.go:171","msg":"trace[1756994643] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"209.220147ms","start":"2026-02-12T03:36:40.778352Z","end":"2026-02-12T03:36:40.987573Z","steps":["trace[1756994643] 'process raft request' (duration: 208.697607ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:40.987685Z","caller":"traceutil/trace.go:171","msg":"trace[229714043] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"208.848404ms","start":"2026-02-12T03:36:40.77883Z","end":"2026-02-12T03:36:40.987678Z","steps":["trace[229714043] 'process raft request' (duration: 208.242361ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:40.987771Z","caller":"traceutil/trace.go:171","msg":"trace[505140867] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"160.376272ms","start":"2026-02-12T03:36:40.82739Z","end":"2026-02-12T03:36:40.987766Z","steps":["trace[505140867] 'process raft request' (duration: 160.098151ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:40.987884Z","caller":"traceutil/trace.go:171","msg":"trace[910800317] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"209.389203ms","start":"2026-02-12T03:36:40.778122Z","end":"2026-02-12T03:36:40.987511Z","steps":["trace[910800317] 'read index received' (duration: 66.081805ms)","trace[910800317] 'applied index is now lower than readState.Index' (duration: 143.306918ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:40.987979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.845169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T03:36:40.987995Z","caller":"traceutil/trace.go:171","msg":"trace[11652736] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:694; }","duration":"209.887681ms","start":"2026-02-12T03:36:40.778103Z","end":"2026-02-12T03:36:40.98799Z","steps":["trace[11652736] 'agreement among raft nodes before linearized reading' (duration: 209.807498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:40.98809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.642251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-6lxqg\" ","response":"range_response_count:1 size:4823"} {"level":"info","ts":"2026-02-12T03:36:40.988103Z","caller":"traceutil/trace.go:171","msg":"trace[657702461] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-6lxqg; range_end:; response_count:1; response_revision:694; }","duration":"160.678123ms","start":"2026-02-12T03:36:40.82742Z","end":"2026-02-12T03:36:40.988098Z","steps":["trace[657702461] 'agreement among raft nodes before linearized reading' (duration: 160.639381ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:29.448471Z","caller":"traceutil/trace.go:171","msg":"trace[336432744] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"101.601766ms","start":"2026-02-12T03:37:29.34685Z","end":"2026-02-12T03:37:29.448452Z","steps":["trace[336432744] 'process raft request' (duration: 32.097027ms)","trace[336432744] 'compare' (duration: 69.434748ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:29.485634Z","caller":"traceutil/trace.go:171","msg":"trace[474694467] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"134.056831ms","start":"2026-02-12T03:37:29.35156Z","end":"2026-02-12T03:37:29.485616Z","steps":["trace[474694467] 'process raft request' (duration: 133.955329ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:42.474187Z","caller":"traceutil/trace.go:171","msg":"trace[1928929500] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"177.863181ms","start":"2026-02-12T03:37:42.296303Z","end":"2026-02-12T03:37:42.474167Z","steps":["trace[1928929500] 'process raft request' (duration: 177.81526ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:42.47481Z","caller":"traceutil/trace.go:171","msg":"trace[752410042] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"179.259717ms","start":"2026-02-12T03:37:42.295522Z","end":"2026-02-12T03:37:42.474782Z","steps":["trace[752410042] 'process raft request' (duration: 171.171617ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:45.869538Z","caller":"traceutil/trace.go:171","msg":"trace[809773137] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"203.342854ms","start":"2026-02-12T03:37:45.666171Z","end":"2026-02-12T03:37:45.869514Z","steps":["trace[809773137] 'read index received' (duration: 203.174421ms)","trace[809773137] 'applied index is now lower than readState.Index' (duration: 167.553µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:45.86965Z","caller":"traceutil/trace.go:171","msg":"trace[925199919] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"212.810396ms","start":"2026-02-12T03:37:45.656829Z","end":"2026-02-12T03:37:45.86964Z","steps":["trace[925199919] 'process raft request' (duration: 212.561472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:45.869859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.67692ms","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-02-12T03:37:45.869892Z","caller":"traceutil/trace.go:171","msg":"trace[628857488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"203.73818ms","start":"2026-02-12T03:37:45.666143Z","end":"2026-02-12T03:37:45.869881Z","steps":["trace[628857488] 'agreement among raft nodes before linearized reading' (duration: 203.623188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:45.870013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.423655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-12T03:37:45.870061Z","caller":"traceutil/trace.go:171","msg":"trace[1572703123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"143.503896ms","start":"2026-02-12T03:37:45.726545Z","end":"2026-02-12T03:37:45.870049Z","steps":["trace[1572703123] 'agreement among raft nodes before linearized reading' (duration: 143.355944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:47.606952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.745843ms","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-02-12T03:37:47.607022Z","caller":"traceutil/trace.go:171","msg":"trace[407923415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"121.845345ms","start":"2026-02-12T03:37:47.48516Z","end":"2026-02-12T03:37:47.607005Z","steps":["trace[407923415] 'range keys from in-memory index tree' (duration: 121.608852ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:47.732981Z","caller":"traceutil/trace.go:171","msg":"trace[1456316527] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"121.53686ms","start":"2026-02-12T03:37:47.61143Z","end":"2026-02-12T03:37:47.732966Z","steps":["trace[1456316527] 'process raft request' (duration: 121.439708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:48.149917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.874544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T03:37:48.150897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.503428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-12T03:37:48.150948Z","caller":"traceutil/trace.go:171","msg":"trace[942366782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"213.55399ms","start":"2026-02-12T03:37:47.937379Z","end":"2026-02-12T03:37:48.150933Z","steps":["trace[942366782] 'range keys from in-memory index tree' (duration: 213.351436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:48.151358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.134445ms","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-02-12T03:37:48.151399Z","caller":"traceutil/trace.go:171","msg":"trace[1550100407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"277.205266ms","start":"2026-02-12T03:37:47.874184Z","end":"2026-02-12T03:37:48.151389Z","steps":["trace[1550100407] 'range keys from in-memory index tree' (duration: 277.014493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:48.152397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.863884ms","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-02-12T03:37:48.15244Z","caller":"traceutil/trace.go:171","msg":"trace[609825944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"200.934215ms","start":"2026-02-12T03:37:47.951496Z","end":"2026-02-12T03:37:48.15243Z","steps":["trace[609825944] 'range keys from in-memory index tree' (duration: 200.766812ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:48.154117Z","caller":"traceutil/trace.go:171","msg":"trace[867341242] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:921; }","duration":"298.14974ms","start":"2026-02-12T03:37:47.85595Z","end":"2026-02-12T03:37:48.1541Z","steps":["trace[867341242] 'count revisions from in-memory index tree' (duration: 293.784852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:48.622886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.705874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:37:48.62304Z","caller":"traceutil/trace.go:171","msg":"trace[509946487] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:960; }","duration":"454.408731ms","start":"2026-02-12T03:37:48.168615Z","end":"2026-02-12T03:37:48.623023Z","steps":["trace[509946487] 'read index received' (duration: 41.78412ms)","trace[509946487] 'applied index is now lower than readState.Index' (duration: 412.623771ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:48.623372Z","caller":"traceutil/trace.go:171","msg":"trace[922335383] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"464.53283ms","start":"2026-02-12T03:37:48.15882Z","end":"2026-02-12T03:37:48.623353Z","steps":["trace[922335383] 'process raft request' (duration: 104.285556ms)","trace[922335383] 'compare' (duration: 359.595272ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:48.623472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:48.158801Z","time spent":"464.630911ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:37:48.623815Z","caller":"traceutil/trace.go:171","msg":"trace[682084112] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"464.675422ms","start":"2026-02-12T03:37:48.15911Z","end":"2026-02-12T03:37:48.623786Z","steps":["trace[682084112] 'process raft request' (duration: 463.865228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:48.624178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:48.159104Z","time spent":"464.979207ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:37:48.624599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.978278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2475"} {"level":"info","ts":"2026-02-12T03:37:48.624788Z","caller":"traceutil/trace.go:171","msg":"trace[841127554] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:924; }","duration":"456.193732ms","start":"2026-02-12T03:37:48.16858Z","end":"2026-02-12T03:37:48.624773Z","steps":["trace[841127554] 'agreement among raft nodes before linearized reading' (duration: 455.901837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:48.624988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:48.168567Z","time spent":"456.407246ms","remote":"127.0.0.1:53564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2498,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-12T03:37:48.625399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.277675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:48.62563Z","caller":"traceutil/trace.go:171","msg":"trace[2002466569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"338.54316ms","start":"2026-02-12T03:37:48.287073Z","end":"2026-02-12T03:37:48.625616Z","steps":["trace[2002466569] 'agreement among raft nodes before linearized reading' (duration: 338.289075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:48.625917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:48.287037Z","time spent":"338.866586ms","remote":"127.0.0.1:53368","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-02-12T03:37:55.747879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.721729ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T03:37:55.747952Z","caller":"traceutil/trace.go:171","msg":"trace[1319515397] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"459.836304ms","start":"2026-02-12T03:37:55.288102Z","end":"2026-02-12T03:37:55.747938Z","steps":["trace[1319515397] 'read index received' (duration: 47.091µs)","trace[1319515397] 'applied index is now lower than readState.Index' (duration: 459.787933ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:37:55.74826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.1574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:37:55.748289Z","caller":"traceutil/trace.go:171","msg":"trace[1143497435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"460.226971ms","start":"2026-02-12T03:37:55.288055Z","end":"2026-02-12T03:37:55.748282Z","steps":["trace[1143497435] 'agreement among raft nodes before linearized reading' (duration: 460.17115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:55.74831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:55.288036Z","time spent":"460.268262ms","remote":"127.0.0.1:53368","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-02-12T03:37:55.748498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.814464ms","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-02-12T03:37:55.748523Z","caller":"traceutil/trace.go:171","msg":"trace[2080493574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:949; }","duration":"372.877014ms","start":"2026-02-12T03:37:55.37564Z","end":"2026-02-12T03:37:55.748517Z","steps":["trace[2080493574] 'agreement among raft nodes before linearized reading' (duration: 372.795403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:55.748546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:55.375621Z","time spent":"372.918485ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T03:38:12.794386Z","caller":"traceutil/trace.go:171","msg":"trace[1886360339] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"102.801981ms","start":"2026-02-12T03:38:12.691561Z","end":"2026-02-12T03:38:12.794363Z","steps":["trace[1886360339] 'process raft request' (duration: 102.347504ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:38:14.622543Z","caller":"traceutil/trace.go:171","msg":"trace[1622056374] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"214.0109ms","start":"2026-02-12T03:38:14.408487Z","end":"2026-02-12T03:38:14.622498Z","steps":["trace[1622056374] 'process raft request' (duration: 213.853488ms)"],"step_count":1}