{"level":"warn","ts":"2026-09-29T05:15:16.874496Z","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-29T05:15:16.874586Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.143: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.143:2380","--initial-cluster=instance=https://162.253.55.143:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.143:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.143: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-29T05:15:16.874652Z","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-29T05:15:16.874659Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.143:2380"]} {"level":"info","ts":"2026-09-29T05:15:16.874677Z","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-29T05:15:16.875257Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.143:2379"]} {"level":"info","ts":"2026-09-29T05:15:16.875324Z","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.143:2380"],"listen-peer-urls":["https://162.253.55.143:2380"],"advertise-client-urls":["https://162.253.55.143:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.143:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.143: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-29T05:15:16.879465Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.932032ms"} {"level":"info","ts":"2026-09-29T05:15:16.88987Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7bf20b54406e85d1","cluster-id":"de54e01795e71fe2"} {"level":"info","ts":"2026-09-29T05:15:16.890006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 switched to configuration voters=()"} {"level":"info","ts":"2026-09-29T05:15:16.890035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 became follower at term 0"} {"level":"info","ts":"2026-09-29T05:15:16.890045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7bf20b54406e85d1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-29T05:15:16.890105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 became follower at term 1"} {"level":"info","ts":"2026-09-29T05:15:16.890187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 switched to configuration voters=(8931213467515258321)"} {"level":"warn","ts":"2026-09-29T05:15:16.89829Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-29T05:15:16.899527Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-29T05:15:16.900536Z","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-29T05:15:16.9022Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7bf20b54406e85d1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-29T05:15:16.902572Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7bf20b54406e85d1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-29T05:15:16.902533Z","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-29T05:15:16.902705Z","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-29T05:15:16.902726Z","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-29T05:15:16.904442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 switched to configuration voters=(8931213467515258321)"} {"level":"info","ts":"2026-09-29T05:15:16.904891Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"de54e01795e71fe2","local-member-id":"7bf20b54406e85d1","added-peer-id":"7bf20b54406e85d1","added-peer-peer-urls":["https://162.253.55.143:2380"]} {"level":"info","ts":"2026-09-29T05:15:16.906056Z","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-29T05:15:16.906081Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.143:2380"} {"level":"info","ts":"2026-09-29T05:15:16.906134Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.143:2380"} {"level":"info","ts":"2026-09-29T05:15:16.906259Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7bf20b54406e85d1","initial-advertise-peer-urls":["https://162.253.55.143:2380"],"listen-peer-urls":["https://162.253.55.143:2380"],"advertise-client-urls":["https://162.253.55.143:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.143:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-29T05:15:16.906297Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-29T05:15:17.791407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 is starting a new election at term 1"} {"level":"info","ts":"2026-09-29T05:15:17.791466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-29T05:15:17.791489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 received MsgPreVoteResp from 7bf20b54406e85d1 at term 1"} {"level":"info","ts":"2026-09-29T05:15:17.79159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 became candidate at term 2"} {"level":"info","ts":"2026-09-29T05:15:17.791601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 received MsgVoteResp from 7bf20b54406e85d1 at term 2"} {"level":"info","ts":"2026-09-29T05:15:17.791615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7bf20b54406e85d1 became leader at term 2"} {"level":"info","ts":"2026-09-29T05:15:17.791632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7bf20b54406e85d1 elected leader 7bf20b54406e85d1 at term 2"} {"level":"info","ts":"2026-09-29T05:15:17.792776Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7bf20b54406e85d1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.143:2379]}","request-path":"/0/members/7bf20b54406e85d1/attributes","cluster-id":"de54e01795e71fe2","publish-timeout":"7s"} {"level":"info","ts":"2026-09-29T05:15:17.792789Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T05:15:17.792868Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T05:15:17.793086Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-29T05:15:17.793158Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-29T05:15:17.793621Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T05:15:17.794161Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"de54e01795e71fe2","local-member-id":"7bf20b54406e85d1","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T05:15:17.794383Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T05:15:17.79445Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T05:15:17.796254Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-29T05:15:17.802141Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.143:2379"} {"level":"warn","ts":"2026-09-29T05:15:18.037958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.547207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:18.038127Z","caller":"traceutil/trace.go:171","msg":"trace[378129582] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"130.780785ms","start":"2026-09-29T05:15:17.907332Z","end":"2026-09-29T05:15:18.038113Z","steps":["trace[378129582] 'count revisions from in-memory index tree' (duration: 130.496094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:18.038454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.199266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:18.038552Z","caller":"traceutil/trace.go:171","msg":"trace[52438238] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"111.334221ms","start":"2026-09-29T05:15:17.927197Z","end":"2026-09-29T05:15:18.038531Z","steps":["trace[52438238] 'count revisions from in-memory index tree' (duration: 111.099732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:18.03906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.301112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:18.039192Z","caller":"traceutil/trace.go:171","msg":"trace[548172191] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"122.423526ms","start":"2026-09-29T05:15:17.916729Z","end":"2026-09-29T05:15:18.039153Z","steps":["trace[548172191] 'range keys from in-memory index tree' (duration: 122.24152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:18.040024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.929934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:18.040072Z","caller":"traceutil/trace.go:171","msg":"trace[1169115548] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"123.028988ms","start":"2026-09-29T05:15:17.917023Z","end":"2026-09-29T05:15:18.040052Z","steps":["trace[1169115548] 'count revisions from in-memory index tree' (duration: 122.846692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:18.040416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.33332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:18.040466Z","caller":"traceutil/trace.go:171","msg":"trace[1457409267] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"113.439925ms","start":"2026-09-29T05:15:17.927004Z","end":"2026-09-29T05:15:18.040444Z","steps":["trace[1457409267] 'range keys from in-memory index tree' (duration: 113.211286ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.626691Z","caller":"traceutil/trace.go:171","msg":"trace[1366618877] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"238.936259ms","start":"2026-09-29T05:15:19.387725Z","end":"2026-09-29T05:15:19.626661Z","steps":["trace[1366618877] 'process raft request' (duration: 238.841826ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.626796Z","caller":"traceutil/trace.go:171","msg":"trace[581479347] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"305.458147ms","start":"2026-09-29T05:15:19.321282Z","end":"2026-09-29T05:15:19.62674Z","steps":["trace[581479347] 'process raft request' (duration: 305.240759ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.626979Z","caller":"traceutil/trace.go:171","msg":"trace[420030610] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"238.617809ms","start":"2026-09-29T05:15:19.38833Z","end":"2026-09-29T05:15:19.626947Z","steps":["trace[420030610] 'process raft request' (duration: 238.278987ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.627048Z","caller":"traceutil/trace.go:171","msg":"trace[270796958] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"238.559217ms","start":"2026-09-29T05:15:19.388477Z","end":"2026-09-29T05:15:19.627036Z","steps":["trace[270796958] 'process raft request' (duration: 238.166443ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.626995Z","caller":"traceutil/trace.go:171","msg":"trace[272481942] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"315.437878ms","start":"2026-09-29T05:15:19.311537Z","end":"2026-09-29T05:15:19.626975Z","steps":["trace[272481942] 'process raft request' (duration: 260.50393ms)","trace[272481942] 'compare' (duration: 54.341207ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:15:19.627477Z","caller":"traceutil/trace.go:171","msg":"trace[676887174] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"238.890457ms","start":"2026-09-29T05:15:19.388572Z","end":"2026-09-29T05:15:19.627463Z","steps":["trace[676887174] 'process raft request' (duration: 238.754303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.627654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.321246Z","time spent":"305.666044ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T05:15:19.627749Z","caller":"traceutil/trace.go:171","msg":"trace[442001076] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"239.085234ms","start":"2026-09-29T05:15:19.388652Z","end":"2026-09-29T05:15:19.627738Z","steps":["trace[442001076] 'process raft request' (duration: 238.98058ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.627742Z","caller":"traceutil/trace.go:171","msg":"trace[2011125957] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"239.084184ms","start":"2026-09-29T05:15:19.388624Z","end":"2026-09-29T05:15:19.627708Z","steps":["trace[2011125957] 'process raft request' (duration: 238.780213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.628283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.311528Z","time spent":"315.831573ms","remote":"127.0.0.1:51202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T05:15:19.628813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.478029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.628863Z","caller":"traceutil/trace.go:171","msg":"trace[1558579656] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"262.571353ms","start":"2026-09-29T05:15:19.366277Z","end":"2026-09-29T05:15:19.628849Z","steps":["trace[1558579656] 'agreement among raft nodes before linearized reading' (duration: 262.434008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.629358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.251469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.629423Z","caller":"traceutil/trace.go:171","msg":"trace[232422909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"307.378503ms","start":"2026-09-29T05:15:19.32203Z","end":"2026-09-29T05:15:19.629409Z","steps":["trace[232422909] 'agreement among raft nodes before linearized reading' (duration: 307.238198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.629458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.322024Z","time spent":"307.424234ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-29T05:15:19.629673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.362654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.629711Z","caller":"traceutil/trace.go:171","msg":"trace[2100326569] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:10; }","duration":"273.434807ms","start":"2026-09-29T05:15:19.356257Z","end":"2026-09-29T05:15:19.629692Z","steps":["trace[2100326569] 'agreement among raft nodes before linearized reading' (duration: 273.386295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.630032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.12657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.630123Z","caller":"traceutil/trace.go:171","msg":"trace[1501649926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"308.304326ms","start":"2026-09-29T05:15:19.321799Z","end":"2026-09-29T05:15:19.630103Z","steps":["trace[1501649926] 'agreement among raft nodes before linearized reading' (duration: 308.098789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.630167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.321788Z","time spent":"308.370928ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-29T05:15:19.631039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.655417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.631072Z","caller":"traceutil/trace.go:171","msg":"trace[434956334] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:10; }","duration":"319.702009ms","start":"2026-09-29T05:15:19.31136Z","end":"2026-09-29T05:15:19.631062Z","steps":["trace[434956334] 'agreement among raft nodes before linearized reading' (duration: 319.651477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.631097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.311352Z","time spent":"319.7389ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-09-29T05:15:19.63153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.955667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.631578Z","caller":"traceutil/trace.go:171","msg":"trace[2134312201] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"244.019578ms","start":"2026-09-29T05:15:19.387546Z","end":"2026-09-29T05:15:19.631566Z","steps":["trace[2134312201] 'agreement among raft nodes before linearized reading' (duration: 243.942736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.631753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.070631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.631806Z","caller":"traceutil/trace.go:171","msg":"trace[968096502] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"244.123662ms","start":"2026-09-29T05:15:19.387673Z","end":"2026-09-29T05:15:19.631797Z","steps":["trace[968096502] 'agreement among raft nodes before linearized reading' (duration: 244.05573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.632079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.012614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.143\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-29T05:15:19.632079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.478185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.632137Z","caller":"traceutil/trace.go:171","msg":"trace[444507528] range","detail":"{range_begin:/registry/masterleases/162.253.55.143; range_end:; response_count:0; response_revision:10; }","duration":"247.066756ms","start":"2026-09-29T05:15:19.38505Z","end":"2026-09-29T05:15:19.632116Z","steps":["trace[444507528] 'agreement among raft nodes before linearized reading' (duration: 246.974202ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.632228Z","caller":"traceutil/trace.go:171","msg":"trace[1071575604] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:10; }","duration":"320.850918ms","start":"2026-09-29T05:15:19.31129Z","end":"2026-09-29T05:15:19.632141Z","steps":["trace[1071575604] 'agreement among raft nodes before linearized reading' (duration: 320.464555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.632298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.311281Z","time spent":"321.003864ms","remote":"127.0.0.1:51204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-09-29T05:15:19.628674Z","caller":"traceutil/trace.go:171","msg":"trace[73560342] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"220.456127ms","start":"2026-09-29T05:15:19.408199Z","end":"2026-09-29T05:15:19.628655Z","steps":["trace[73560342] 'read index received' (duration: 163.85302ms)","trace[73560342] 'applied index is now lower than readState.Index' (duration: 56.601507ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:15:19.853809Z","caller":"traceutil/trace.go:171","msg":"trace[60511115] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"437.35895ms","start":"2026-09-29T05:15:19.416408Z","end":"2026-09-29T05:15:19.853767Z","steps":["trace[60511115] 'process raft request' (duration: 376.318867ms)","trace[60511115] 'compare' (duration: 60.920729ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T05:15:19.853981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.416393Z","time spent":"437.498066ms","remote":"127.0.0.1:51320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T05:15:19.855019Z","caller":"traceutil/trace.go:171","msg":"trace[1354969322] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:13; }","duration":"222.459148ms","start":"2026-09-29T05:15:19.632543Z","end":"2026-09-29T05:15:19.855002Z","steps":["trace[1354969322] 'read index received' (duration: 160.201242ms)","trace[1354969322] 'applied index is now lower than readState.Index' (duration: 62.256536ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:15:19.855284Z","caller":"traceutil/trace.go:171","msg":"trace[1077267511] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"219.823135ms","start":"2026-09-29T05:15:19.635421Z","end":"2026-09-29T05:15:19.855244Z","steps":["trace[1077267511] 'process raft request' (duration: 219.488663ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.855329Z","caller":"traceutil/trace.go:171","msg":"trace[919379203] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"223.134232ms","start":"2026-09-29T05:15:19.632176Z","end":"2026-09-29T05:15:19.85531Z","steps":["trace[919379203] 'process raft request' (duration: 222.463289ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.855374Z","caller":"traceutil/trace.go:171","msg":"trace[1044487853] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"217.904958ms","start":"2026-09-29T05:15:19.637457Z","end":"2026-09-29T05:15:19.855362Z","steps":["trace[1044487853] 'process raft request' (duration: 217.512774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.855567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.80511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-29T05:15:19.855568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.703139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T05:15:19.855599Z","caller":"traceutil/trace.go:171","msg":"trace[786973303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"220.864192ms","start":"2026-09-29T05:15:19.634723Z","end":"2026-09-29T05:15:19.855588Z","steps":["trace[786973303] 'agreement among raft nodes before linearized reading' (duration: 220.763588ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.855598Z","caller":"traceutil/trace.go:171","msg":"trace[1346696438] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"220.379185ms","start":"2026-09-29T05:15:19.635205Z","end":"2026-09-29T05:15:19.855584Z","steps":["trace[1346696438] 'process raft request' (duration: 219.607428ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.85561Z","caller":"traceutil/trace.go:171","msg":"trace[430073172] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:19; }","duration":"444.774781ms","start":"2026-09-29T05:15:19.410823Z","end":"2026-09-29T05:15:19.855598Z","steps":["trace[430073172] 'agreement among raft nodes before linearized reading' (duration: 444.661057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.857118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.410808Z","time spent":"446.292886ms","remote":"127.0.0.1:51518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-09-29T05:15:19.855616Z","caller":"traceutil/trace.go:171","msg":"trace[1447872645] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"223.317258ms","start":"2026-09-29T05:15:19.632261Z","end":"2026-09-29T05:15:19.855578Z","steps":["trace[1447872645] 'process raft request' (duration: 222.450148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.855671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.738359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-2w6lj\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-09-29T05:15:19.85738Z","caller":"traceutil/trace.go:171","msg":"trace[1417870746] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-2w6lj; range_end:; response_count:1; response_revision:19; }","duration":"226.47193ms","start":"2026-09-29T05:15:19.630889Z","end":"2026-09-29T05:15:19.857361Z","steps":["trace[1417870746] 'agreement among raft nodes before linearized reading' (duration: 224.741988ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.855678Z","caller":"traceutil/trace.go:171","msg":"trace[368616081] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"220.282052ms","start":"2026-09-29T05:15:19.634963Z","end":"2026-09-29T05:15:19.855245Z","steps":["trace[368616081] 'process raft request' (duration: 219.792995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.855718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.474059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-09-29T05:15:19.858019Z","caller":"traceutil/trace.go:171","msg":"trace[1165650699] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"353.74369ms","start":"2026-09-29T05:15:19.504217Z","end":"2026-09-29T05:15:19.85796Z","steps":["trace[1165650699] 'agreement among raft nodes before linearized reading' (duration: 351.47813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.85812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.504202Z","time spent":"353.897145ms","remote":"127.0.0.1:51242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-29T05:15:19.855333Z","caller":"traceutil/trace.go:171","msg":"trace[1221445667] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"219.764283ms","start":"2026-09-29T05:15:19.635557Z","end":"2026-09-29T05:15:19.855321Z","steps":["trace[1221445667] 'process raft request' (duration: 219.372909ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.855756Z","caller":"traceutil/trace.go:171","msg":"trace[977416290] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"220.54584ms","start":"2026-09-29T05:15:19.6352Z","end":"2026-09-29T05:15:19.855746Z","steps":["trace[977416290] 'process raft request' (duration: 219.642919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.855764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.089134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-09-29T05:15:19.855815Z","caller":"traceutil/trace.go:171","msg":"trace[2080552050] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"220.532219ms","start":"2026-09-29T05:15:19.635274Z","end":"2026-09-29T05:15:19.855806Z","steps":["trace[2080552050] 'process raft request' (duration: 219.605567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.855843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.553862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-09-29T05:15:19.855974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.116591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"warn","ts":"2026-09-29T05:15:19.855983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.021657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-29T05:15:19.856039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.892043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-09-29T05:15:19.858289Z","caller":"traceutil/trace.go:171","msg":"trace[216537179] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"170.201975ms","start":"2026-09-29T05:15:19.688074Z","end":"2026-09-29T05:15:19.858276Z","steps":["trace[216537179] 'agreement among raft nodes before linearized reading' (duration: 167.860402ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.858414Z","caller":"traceutil/trace.go:171","msg":"trace[1600682851] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"417.747188ms","start":"2026-09-29T05:15:19.440649Z","end":"2026-09-29T05:15:19.858397Z","steps":["trace[1600682851] 'agreement among raft nodes before linearized reading' (duration: 415.089534ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.858454Z","caller":"traceutil/trace.go:171","msg":"trace[985723505] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"170.173024ms","start":"2026-09-29T05:15:19.688268Z","end":"2026-09-29T05:15:19.858441Z","steps":["trace[985723505] 'agreement among raft nodes before linearized reading' (duration: 167.557291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:15:19.858473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:15:19.440637Z","time spent":"417.82223ms","remote":"127.0.0.1:51242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-29T05:15:19.858615Z","caller":"traceutil/trace.go:171","msg":"trace[2029826348] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"223.761254ms","start":"2026-09-29T05:15:19.634814Z","end":"2026-09-29T05:15:19.858575Z","steps":["trace[2029826348] 'agreement among raft nodes before linearized reading' (duration: 221.10666ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:19.85867Z","caller":"traceutil/trace.go:171","msg":"trace[1301356078] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:19; }","duration":"170.781785ms","start":"2026-09-29T05:15:19.687862Z","end":"2026-09-29T05:15:19.858643Z","steps":["trace[1301356078] 'agreement among raft nodes before linearized reading' (duration: 168.045678ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.068509Z","caller":"traceutil/trace.go:171","msg":"trace[39040807] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"102.576388ms","start":"2026-09-29T05:15:55.965916Z","end":"2026-09-29T05:15:56.068493Z","steps":["trace[39040807] 'process raft request' (duration: 102.532857ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.068686Z","caller":"traceutil/trace.go:171","msg":"trace[1816201614] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"102.865887ms","start":"2026-09-29T05:15:55.965806Z","end":"2026-09-29T05:15:56.068671Z","steps":["trace[1816201614] 'process raft request' (duration: 102.573889ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.082083Z","caller":"traceutil/trace.go:171","msg":"trace[387471522] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"107.876342ms","start":"2026-09-29T05:15:55.974179Z","end":"2026-09-29T05:15:56.082055Z","steps":["trace[387471522] 'process raft request' (duration: 107.586404ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.084183Z","caller":"traceutil/trace.go:171","msg":"trace[1020748044] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"109.823818ms","start":"2026-09-29T05:15:55.974346Z","end":"2026-09-29T05:15:56.084169Z","steps":["trace[1020748044] 'process raft request' (duration: 107.622874ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.084526Z","caller":"traceutil/trace.go:171","msg":"trace[1348370236] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"102.393352ms","start":"2026-09-29T05:15:55.982109Z","end":"2026-09-29T05:15:56.084502Z","steps":["trace[1348370236] 'process raft request' (duration: 102.142755ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.189865Z","caller":"traceutil/trace.go:171","msg":"trace[1092488715] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"113.234912ms","start":"2026-09-29T05:15:56.076606Z","end":"2026-09-29T05:15:56.189841Z","steps":["trace[1092488715] 'process raft request' (duration: 113.16554ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.189924Z","caller":"traceutil/trace.go:171","msg":"trace[1578588658] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"114.310773ms","start":"2026-09-29T05:15:56.075581Z","end":"2026-09-29T05:15:56.189892Z","steps":["trace[1578588658] 'process raft request' (duration: 114.141879ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.189876Z","caller":"traceutil/trace.go:171","msg":"trace[2058935693] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"115.247791ms","start":"2026-09-29T05:15:56.074592Z","end":"2026-09-29T05:15:56.18984Z","steps":["trace[2058935693] 'process raft request' (duration: 110.895395ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:15:56.193954Z","caller":"traceutil/trace.go:171","msg":"trace[1200485838] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"108.950199ms","start":"2026-09-29T05:15:56.084972Z","end":"2026-09-29T05:15:56.193922Z","steps":["trace[1200485838] 'process raft request' (duration: 108.433994ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:16:02.409451Z","caller":"traceutil/trace.go:171","msg":"trace[1598643355] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"128.742999ms","start":"2026-09-29T05:16:02.280665Z","end":"2026-09-29T05:16:02.409408Z","steps":["trace[1598643355] 'process raft request' (duration: 72.200521ms)","trace[1598643355] 'compare' (duration: 55.968302ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:16:02.988966Z","caller":"traceutil/trace.go:171","msg":"trace[830954225] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"262.81828ms","start":"2026-09-29T05:16:02.726116Z","end":"2026-09-29T05:16:02.988934Z","steps":["trace[830954225] 'read index received' (duration: 225.971739ms)","trace[830954225] 'applied index is now lower than readState.Index' (duration: 36.844461ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:16:02.989288Z","caller":"traceutil/trace.go:171","msg":"trace[1022536676] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"559.143496ms","start":"2026-09-29T05:16:02.430083Z","end":"2026-09-29T05:16:02.989227Z","steps":["trace[1022536676] 'process raft request' (duration: 521.991947ms)","trace[1022536676] 'compare' (duration: 36.426959ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T05:16:02.989432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:16:02.430056Z","time spent":"559.286051ms","remote":"127.0.0.1:51326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T05:16:02.989546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.429238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T05:16:02.989866Z","caller":"traceutil/trace.go:171","msg":"trace[1433139494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"263.871861ms","start":"2026-09-29T05:16:02.725971Z","end":"2026-09-29T05:16:02.989843Z","steps":["trace[1433139494] 'agreement among raft nodes before linearized reading' (duration: 263.329114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:16:02.989545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.971333ms","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-29T05:16:02.989616Z","caller":"traceutil/trace.go:171","msg":"trace[1421937280] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"470.660695ms","start":"2026-09-29T05:16:02.518913Z","end":"2026-09-29T05:16:02.989573Z","steps":["trace[1421937280] 'process raft request' (duration: 469.911363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:16:02.99011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:16:02.518894Z","time spent":"471.17588ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-29T05:16:02.990437Z","caller":"traceutil/trace.go:171","msg":"trace[1711144331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"102.469977ms","start":"2026-09-29T05:16:02.887503Z","end":"2026-09-29T05:16:02.989973Z","steps":["trace[1711144331] 'agreement among raft nodes before linearized reading' (duration: 101.862549ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:16:20.130974Z","caller":"traceutil/trace.go:171","msg":"trace[947029285] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"304.708984ms","start":"2026-09-29T05:16:19.826227Z","end":"2026-09-29T05:16:20.130936Z","steps":["trace[947029285] 'process raft request' (duration: 304.484549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:16:20.131235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:16:19.826207Z","time spent":"304.92212ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T05:17:39.043033Z","caller":"traceutil/trace.go:171","msg":"trace[16607167] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"126.841498ms","start":"2026-09-29T05:17:38.916152Z","end":"2026-09-29T05:17:39.042993Z","steps":["trace[16607167] 'read index received' (duration: 120.025155ms)","trace[16607167] 'applied index is now lower than readState.Index' (duration: 6.814903ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T05:17:39.043309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.137857ms","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-29T05:17:39.043347Z","caller":"traceutil/trace.go:171","msg":"trace[859687414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1886; }","duration":"127.259069ms","start":"2026-09-29T05:17:38.916076Z","end":"2026-09-29T05:17:39.043335Z","steps":["trace[859687414] 'agreement among raft nodes before linearized reading' (duration: 127.050453ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:17:39.04347Z","caller":"traceutil/trace.go:171","msg":"trace[1287518801] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"176.891523ms","start":"2026-09-29T05:17:38.866534Z","end":"2026-09-29T05:17:39.043425Z","steps":["trace[1287518801] 'process raft request' (duration: 169.661449ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:17:39.265791Z","caller":"traceutil/trace.go:171","msg":"trace[427027894] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"137.713762ms","start":"2026-09-29T05:17:39.128034Z","end":"2026-09-29T05:17:39.265747Z","steps":["trace[427027894] 'process raft request' (duration: 57.478439ms)","trace[427027894] 'compare' (duration: 80.004618ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:17:55.074735Z","caller":"traceutil/trace.go:171","msg":"trace[1916875123] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"105.660791ms","start":"2026-09-29T05:17:54.969037Z","end":"2026-09-29T05:17:55.074698Z","steps":["trace[1916875123] 'process raft request' (duration: 105.415596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:18:17.959051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.481522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-29T05:18:17.959151Z","caller":"traceutil/trace.go:171","msg":"trace[1150659164] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2149; }","duration":"149.648166ms","start":"2026-09-29T05:18:17.809483Z","end":"2026-09-29T05:18:17.959131Z","steps":["trace[1150659164] 'range keys from in-memory index tree' (duration: 149.301538ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:18:18.339505Z","caller":"traceutil/trace.go:171","msg":"trace[604029692] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"176.907031ms","start":"2026-09-29T05:18:18.162557Z","end":"2026-09-29T05:18:18.339464Z","steps":["trace[604029692] 'process raft request' (duration: 176.737047ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:22.403559Z","caller":"traceutil/trace.go:171","msg":"trace[1710935378] linearizableReadLoop","detail":"{readStateIndex:4981; appliedIndex:4980; }","duration":"105.547972ms","start":"2026-09-29T05:24:22.297989Z","end":"2026-09-29T05:24:22.403537Z","steps":["trace[1710935378] 'read index received' (duration: 49.09567ms)","trace[1710935378] 'applied index is now lower than readState.Index' (duration: 56.451552ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T05:24:22.403788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.781587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a05d4251-a253-4e45-a107-286492f75fb7\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-09-29T05:24:22.403835Z","caller":"traceutil/trace.go:171","msg":"trace[163851222] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a05d4251-a253-4e45-a107-286492f75fb7; range_end:; response_count:1; response_revision:4801; }","duration":"105.856189ms","start":"2026-09-29T05:24:22.297951Z","end":"2026-09-29T05:24:22.403807Z","steps":["trace[163851222] 'agreement among raft nodes before linearized reading' (duration: 105.662915ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:22.403964Z","caller":"traceutil/trace.go:171","msg":"trace[106789250] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"155.49209ms","start":"2026-09-29T05:24:22.248158Z","end":"2026-09-29T05:24:22.403651Z","steps":["trace[106789250] 'process raft request' (duration: 98.995297ms)","trace[106789250] 'compare' (duration: 56.193986ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:24:33.061985Z","caller":"traceutil/trace.go:171","msg":"trace[1867825269] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"117.187094ms","start":"2026-09-29T05:24:32.944767Z","end":"2026-09-29T05:24:33.061954Z","steps":["trace[1867825269] 'process raft request' (duration: 117.02237ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:41.90801Z","caller":"traceutil/trace.go:171","msg":"trace[2062677275] linearizableReadLoop","detail":"{readStateIndex:5327; appliedIndex:5326; }","duration":"129.031204ms","start":"2026-09-29T05:24:41.778951Z","end":"2026-09-29T05:24:41.907982Z","steps":["trace[2062677275] 'read index received' (duration: 128.762698ms)","trace[2062677275] 'applied index is now lower than readState.Index' (duration: 267.456µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T05:24:41.908264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.28263ms","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-29T05:24:41.908301Z","caller":"traceutil/trace.go:171","msg":"trace[1051784143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5135; }","duration":"129.360012ms","start":"2026-09-29T05:24:41.778931Z","end":"2026-09-29T05:24:41.908291Z","steps":["trace[1051784143] 'agreement among raft nodes before linearized reading' (duration: 129.159408ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:41.908707Z","caller":"traceutil/trace.go:171","msg":"trace[257862566] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"156.98843ms","start":"2026-09-29T05:24:41.751707Z","end":"2026-09-29T05:24:41.908696Z","steps":["trace[257862566] 'process raft request' (duration: 156.12667ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:42.238748Z","caller":"traceutil/trace.go:171","msg":"trace[1153441796] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5327; }","duration":"239.490082ms","start":"2026-09-29T05:24:41.999214Z","end":"2026-09-29T05:24:42.238704Z","steps":["trace[1153441796] 'read index received' (duration: 167.445594ms)","trace[1153441796] 'applied index is now lower than readState.Index' (duration: 72.043138ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:24:42.238883Z","caller":"traceutil/trace.go:171","msg":"trace[1178572322] transaction","detail":"{read_only:false; response_revision:5136; number_of_response:1; }","duration":"324.242829ms","start":"2026-09-29T05:24:41.914627Z","end":"2026-09-29T05:24:42.23887Z","steps":["trace[1178572322] 'process raft request' (duration: 252.010537ms)","trace[1178572322] 'compare' (duration: 71.835962ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T05:24:42.238991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T05:24:41.914604Z","time spent":"324.306029ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T05:24:42.239167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.990621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-09-29T05:24:42.239259Z","caller":"traceutil/trace.go:171","msg":"trace[548488912] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5136; }","duration":"216.127854ms","start":"2026-09-29T05:24:42.02311Z","end":"2026-09-29T05:24:42.239238Z","steps":["trace[548488912] 'agreement among raft nodes before linearized reading' (duration: 215.917459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:24:42.239398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.784061ms","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-29T05:24:42.239441Z","caller":"traceutil/trace.go:171","msg":"trace[220833205] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5136; }","duration":"190.883693ms","start":"2026-09-29T05:24:42.048544Z","end":"2026-09-29T05:24:42.239428Z","steps":["trace[220833205] 'agreement among raft nodes before linearized reading' (duration: 190.770991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T05:24:42.239674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.427464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-09-29T05:24:42.239813Z","caller":"traceutil/trace.go:171","msg":"trace[478637591] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5136; }","duration":"240.612229ms","start":"2026-09-29T05:24:41.999174Z","end":"2026-09-29T05:24:42.239786Z","steps":["trace[478637591] 'agreement among raft nodes before linearized reading' (duration: 240.110037ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:42.475672Z","caller":"traceutil/trace.go:171","msg":"trace[76091040] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"137.41904ms","start":"2026-09-29T05:24:42.33822Z","end":"2026-09-29T05:24:42.475639Z","steps":["trace[76091040] 'process raft request' (duration: 59.915134ms)","trace[76091040] 'compare' (duration: 77.24701ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T05:24:42.534161Z","caller":"traceutil/trace.go:171","msg":"trace[1818899788] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"195.117252ms","start":"2026-09-29T05:24:42.338986Z","end":"2026-09-29T05:24:42.534103Z","steps":["trace[1818899788] 'process raft request' (duration: 194.901767ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:42.534241Z","caller":"traceutil/trace.go:171","msg":"trace[1877393291] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"194.372825ms","start":"2026-09-29T05:24:42.339836Z","end":"2026-09-29T05:24:42.534209Z","steps":["trace[1877393291] 'process raft request' (duration: 194.189111ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:42.53452Z","caller":"traceutil/trace.go:171","msg":"trace[1812310306] linearizableReadLoop","detail":"{readStateIndex:5334; appliedIndex:5331; }","duration":"122.940621ms","start":"2026-09-29T05:24:42.411568Z","end":"2026-09-29T05:24:42.534509Z","steps":["trace[1812310306] 'read index received' (duration: 122.280996ms)","trace[1812310306] 'applied index is now lower than readState.Index' (duration: 658.605µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T05:24:42.534731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.147556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-29T05:24:42.534782Z","caller":"traceutil/trace.go:171","msg":"trace[1495815375] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5142; }","duration":"123.242128ms","start":"2026-09-29T05:24:42.411521Z","end":"2026-09-29T05:24:42.534763Z","steps":["trace[1495815375] 'agreement among raft nodes before linearized reading' (duration: 123.036394ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:24:42.534794Z","caller":"traceutil/trace.go:171","msg":"trace[1973234384] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"194.554929ms","start":"2026-09-29T05:24:42.340221Z","end":"2026-09-29T05:24:42.534776Z","steps":["trace[1973234384] 'process raft request' (duration: 193.933934ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T05:25:17.899776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2893} {"level":"info","ts":"2026-09-29T05:25:18.068185Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2893,"took":"166.354628ms","hash":90576179,"current-db-size-bytes":53440512,"current-db-size":"53 MB","current-db-size-in-use-bytes":29016064,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-29T05:25:18.068252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":90576179,"revision":2893,"compact-revision":-1} {"level":"info","ts":"2026-09-29T05:30:17.908368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5482} {"level":"info","ts":"2026-09-29T05:30:17.987885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5482,"took":"77.248535ms","hash":141500248,"current-db-size-bytes":53440512,"current-db-size":"53 MB","current-db-size-in-use-bytes":18694144,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-29T05:30:17.988017Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":141500248,"revision":5482,"compact-revision":2893}