{"level":"warn","ts":"2026-10-19T01:49:17.738259Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-19T01:49:17.738634Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.44: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.44:2380","--initial-cluster=instance=https://162.253.55.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.44: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-10-19T01:49:17.738729Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-19T01:49:17.73876Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-10-19T01:49:17.73879Z","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-10-19T01:49:17.739403Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"]} {"level":"info","ts":"2026-10-19T01:49:17.7395Z","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.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.44: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-10-19T01:49:17.743649Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.895372ms"} {"level":"info","ts":"2026-10-19T01:49:17.751423Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4cb6cb294c6743","cluster-id":"77f9414eebec887e"} {"level":"info","ts":"2026-10-19T01:49:17.751502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=()"} {"level":"info","ts":"2026-10-19T01:49:17.751532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 0"} {"level":"info","ts":"2026-10-19T01:49:17.751573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4cb6cb294c6743 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-19T01:49:17.75159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became follower at term 1"} {"level":"info","ts":"2026-10-19T01:49:17.751647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"warn","ts":"2026-10-19T01:49:17.760031Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-19T01:49:17.761337Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-19T01:49:17.762455Z","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-10-19T01:49:17.764266Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4cb6cb294c6743","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-19T01:49:17.764599Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4cb6cb294c6743","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-19T01:49:17.765312Z","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-10-19T01:49:17.765387Z","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-10-19T01:49:17.765395Z","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-10-19T01:49:17.765753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 switched to configuration voters=(814226616334706499)"} {"level":"info","ts":"2026-10-19T01:49:17.765919Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","added-peer-id":"b4cb6cb294c6743","added-peer-peer-urls":["https://162.253.55.44:2380"]} {"level":"info","ts":"2026-10-19T01:49:17.767668Z","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-10-19T01:49:17.767982Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-10-19T01:49:17.768006Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.44:2380"} {"level":"info","ts":"2026-10-19T01:49:17.768208Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4cb6cb294c6743","initial-advertise-peer-urls":["https://162.253.55.44:2380"],"listen-peer-urls":["https://162.253.55.44:2380"],"advertise-client-urls":["https://162.253.55.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-19T01:49:17.768237Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-19T01:49:18.152075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 is starting a new election at term 1"} {"level":"info","ts":"2026-10-19T01:49:18.152124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-19T01:49:18.152143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgPreVoteResp from b4cb6cb294c6743 at term 1"} {"level":"info","ts":"2026-10-19T01:49:18.152765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became candidate at term 2"} {"level":"info","ts":"2026-10-19T01:49:18.152782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 received MsgVoteResp from b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-10-19T01:49:18.152866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4cb6cb294c6743 became leader at term 2"} {"level":"info","ts":"2026-10-19T01:49:18.152882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4cb6cb294c6743 elected leader b4cb6cb294c6743 at term 2"} {"level":"info","ts":"2026-10-19T01:49:18.159116Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:49:18.161408Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4cb6cb294c6743","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.44:2379]}","request-path":"/0/members/b4cb6cb294c6743/attributes","cluster-id":"77f9414eebec887e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-19T01:49:18.161506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-19T01:49:18.161547Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-19T01:49:18.162348Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-19T01:49:18.162397Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-19T01:49:18.163719Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77f9414eebec887e","local-member-id":"b4cb6cb294c6743","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:49:18.163862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.44:2379"} {"level":"info","ts":"2026-10-19T01:49:18.16413Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:49:18.164279Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-19T01:49:18.165653Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-19T01:49:27.540734Z","caller":"traceutil/trace.go:171","msg":"trace[259657940] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"155.911873ms","start":"2026-10-19T01:49:27.384804Z","end":"2026-10-19T01:49:27.540716Z","steps":["trace[259657940] 'process raft request' (duration: 155.807581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.808523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.929155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T01:49:27.808702Z","caller":"traceutil/trace.go:171","msg":"trace[1517873257] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"420.094564ms","start":"2026-10-19T01:49:27.388595Z","end":"2026-10-19T01:49:27.80869Z","steps":["trace[1517873257] 'process raft request' (duration: 420.010793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.808789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:49:27.388582Z","time spent":"420.184525ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:49:27.808723Z","caller":"traceutil/trace.go:171","msg":"trace[52608492] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"423.648858ms","start":"2026-10-19T01:49:27.385042Z","end":"2026-10-19T01:49:27.808691Z","steps":["trace[52608492] 'process raft request' (duration: 255.143734ms)","trace[52608492] 'compare' (duration: 167.810894ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:49:27.808852Z","caller":"traceutil/trace.go:171","msg":"trace[791618329] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:314; }","duration":"379.881401ms","start":"2026-10-19T01:49:27.428901Z","end":"2026-10-19T01:49:27.808783Z","steps":["trace[791618329] 'read index received' (duration: 111.831002ms)","trace[791618329] 'applied index is now lower than readState.Index' (duration: 268.048279ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:49:27.808993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:49:27.385028Z","time spent":"423.874562ms","remote":"127.0.0.1:41640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:49:27.809163Z","caller":"traceutil/trace.go:171","msg":"trace[1710987350] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"259.876116ms","start":"2026-10-19T01:49:27.549266Z","end":"2026-10-19T01:49:27.809142Z","steps":["trace[1710987350] 'process raft request' (duration: 259.387729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.809271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.295908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-10-19T01:49:27.809321Z","caller":"traceutil/trace.go:171","msg":"trace[1317882292] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:306; }","duration":"380.432901ms","start":"2026-10-19T01:49:27.428875Z","end":"2026-10-19T01:49:27.809308Z","steps":["trace[1317882292] 'agreement among raft nodes before linearized reading' (duration: 380.037565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.809455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.55075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:49:27.809494Z","caller":"traceutil/trace.go:171","msg":"trace[919695328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:307; }","duration":"370.62158ms","start":"2026-10-19T01:49:27.438862Z","end":"2026-10-19T01:49:27.809483Z","steps":["trace[919695328] 'agreement among raft nodes before linearized reading' (duration: 370.537409ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:49:27.809522Z","caller":"traceutil/trace.go:171","msg":"trace[290780063] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"259.308356ms","start":"2026-10-19T01:49:27.550202Z","end":"2026-10-19T01:49:27.80951Z","steps":["trace[290780063] 'process raft request' (duration: 258.8488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.809575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.739583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-10-19T01:49:27.809607Z","caller":"traceutil/trace.go:171","msg":"trace[1070282655] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:307; }","duration":"234.807174ms","start":"2026-10-19T01:49:27.574792Z","end":"2026-10-19T01:49:27.809599Z","steps":["trace[1070282655] 'agreement among raft nodes before linearized reading' (duration: 234.753693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.809528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:49:27.438846Z","time spent":"370.675581ms","remote":"127.0.0.1:41478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-19T01:49:27.809691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.18277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-10-19T01:49:27.809717Z","caller":"traceutil/trace.go:171","msg":"trace[1241175832] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:307; }","duration":"283.249941ms","start":"2026-10-19T01:49:27.526461Z","end":"2026-10-19T01:49:27.809711Z","steps":["trace[1241175832] 'agreement among raft nodes before linearized reading' (duration: 283.208091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.809699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.783281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-10-19T01:49:27.809817Z","caller":"traceutil/trace.go:171","msg":"trace[1717997375] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:307; }","duration":"134.923553ms","start":"2026-10-19T01:49:27.674878Z","end":"2026-10-19T01:49:27.809802Z","steps":["trace[1717997375] 'agreement among raft nodes before linearized reading' (duration: 134.664269ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.809884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.380934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-10-19T01:49:27.809935Z","caller":"traceutil/trace.go:171","msg":"trace[1333044815] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:307; }","duration":"333.494386ms","start":"2026-10-19T01:49:27.476425Z","end":"2026-10-19T01:49:27.80992Z","steps":["trace[1333044815] 'agreement among raft nodes before linearized reading' (duration: 333.385574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.809968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.101097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-19T01:49:27.80998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:49:27.476409Z","time spent":"333.562717ms","remote":"127.0.0.1:41686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-10-19T01:49:27.810009Z","caller":"traceutil/trace.go:171","msg":"trace[1064246853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:307; }","duration":"321.163898ms","start":"2026-10-19T01:49:27.488832Z","end":"2026-10-19T01:49:27.809996Z","steps":["trace[1064246853] 'agreement among raft nodes before linearized reading' (duration: 321.073156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:49:27.810037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:49:27.488823Z","time spent":"321.206569ms","remote":"127.0.0.1:41746","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-10-19T01:49:27.810087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:49:27.428862Z","time spent":"380.604252ms","remote":"127.0.0.1:41686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-10-19T01:49:27.809926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.545379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-19T01:49:27.810249Z","caller":"traceutil/trace.go:171","msg":"trace[1138897676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:307; }","duration":"184.910164ms","start":"2026-10-19T01:49:27.62528Z","end":"2026-10-19T01:49:27.81019Z","steps":["trace[1138897676] 'agreement among raft nodes before linearized reading' (duration: 184.207333ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:50:00.645959Z","caller":"traceutil/trace.go:171","msg":"trace[949364608] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"113.986989ms","start":"2026-10-19T01:50:00.531954Z","end":"2026-10-19T01:50:00.645941Z","steps":["trace[949364608] 'process raft request' (duration: 113.724186ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:50:08.738537Z","caller":"traceutil/trace.go:171","msg":"trace[404209697] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"267.776307ms","start":"2026-10-19T01:50:08.470718Z","end":"2026-10-19T01:50:08.738494Z","steps":["trace[404209697] 'process raft request' (duration: 259.8694ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:50:09.212181Z","caller":"traceutil/trace.go:171","msg":"trace[710150777] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"302.777983ms","start":"2026-10-19T01:50:08.90938Z","end":"2026-10-19T01:50:09.212158Z","steps":["trace[710150777] 'read index received' (duration: 302.022393ms)","trace[710150777] 'applied index is now lower than readState.Index' (duration: 754.66µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:50:09.212255Z","caller":"traceutil/trace.go:171","msg":"trace[19808578] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"457.186418ms","start":"2026-10-19T01:50:08.755036Z","end":"2026-10-19T01:50:09.212222Z","steps":["trace[19808578] 'process raft request' (duration: 456.644981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:50:09.212383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.981446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-19T01:50:09.212416Z","caller":"traceutil/trace.go:171","msg":"trace[2058043647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"303.066196ms","start":"2026-10-19T01:50:08.909339Z","end":"2026-10-19T01:50:09.212405Z","steps":["trace[2058043647] 'agreement among raft nodes before linearized reading' (duration: 302.931214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:50:09.212428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:50:08.755008Z","time spent":"457.321859ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-19T01:50:09.212476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:50:08.909321Z","time spent":"303.143906ms","remote":"127.0.0.1:41746","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-10-19T01:50:09.212732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.41734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:50:09.212766Z","caller":"traceutil/trace.go:171","msg":"trace[1804065077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"223.468841ms","start":"2026-10-19T01:50:08.989286Z","end":"2026-10-19T01:50:09.212755Z","steps":["trace[1804065077] 'agreement among raft nodes before linearized reading' (duration: 223.41697ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:50:23.731055Z","caller":"traceutil/trace.go:171","msg":"trace[938191141] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"156.096996ms","start":"2026-10-19T01:50:23.574934Z","end":"2026-10-19T01:50:23.731031Z","steps":["trace[938191141] 'process raft request' (duration: 155.904103ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:50:55.029474Z","caller":"traceutil/trace.go:171","msg":"trace[544110747] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1210; }","duration":"121.34542ms","start":"2026-10-19T01:50:54.908106Z","end":"2026-10-19T01:50:55.029452Z","steps":["trace[544110747] 'read index received' (duration: 40.623068ms)","trace[544110747] 'applied index is now lower than readState.Index' (duration: 80.721342ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:50:55.029608Z","caller":"traceutil/trace.go:171","msg":"trace[109660562] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"232.173138ms","start":"2026-10-19T01:50:54.797423Z","end":"2026-10-19T01:50:55.029596Z","steps":["trace[109660562] 'process raft request' (duration: 151.158044ms)","trace[109660562] 'compare' (duration: 80.61554ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:50:55.029718Z","caller":"traceutil/trace.go:171","msg":"trace[1842851795] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"206.708029ms","start":"2026-10-19T01:50:54.823002Z","end":"2026-10-19T01:50:55.02971Z","steps":["trace[1842851795] 'process raft request' (duration: 206.406376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:50:55.029883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.767025ms","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-10-19T01:50:55.029916Z","caller":"traceutil/trace.go:171","msg":"trace[2012866601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"121.867816ms","start":"2026-10-19T01:50:54.90804Z","end":"2026-10-19T01:50:55.029908Z","steps":["trace[2012866601] 'agreement among raft nodes before linearized reading' (duration: 121.737514ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:51:21.42934Z","caller":"traceutil/trace.go:171","msg":"trace[710772898] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1494; }","duration":"272.413957ms","start":"2026-10-19T01:51:21.156875Z","end":"2026-10-19T01:51:21.429289Z","steps":["trace[710772898] 'read index received' (duration: 181.279362ms)","trace[710772898] 'applied index is now lower than readState.Index' (duration: 91.133175ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:51:21.429683Z","caller":"traceutil/trace.go:171","msg":"trace[644314836] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"306.774274ms","start":"2026-10-19T01:51:21.122888Z","end":"2026-10-19T01:51:21.429663Z","steps":["trace[644314836] 'process raft request' (duration: 215.32166ms)","trace[644314836] 'compare' (duration: 90.758505ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:51:21.429889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.06056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-19T01:51:21.429912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.021725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-10-19T01:51:21.429916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:51:21.122865Z","time spent":"306.864436ms","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:51:21.429991Z","caller":"traceutil/trace.go:171","msg":"trace[231744113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1447; }","duration":"216.207074ms","start":"2026-10-19T01:51:21.213762Z","end":"2026-10-19T01:51:21.429969Z","steps":["trace[231744113] 'agreement among raft nodes before linearized reading' (duration: 215.970748ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:51:21.430006Z","caller":"traceutil/trace.go:171","msg":"trace[1534273764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1447; }","duration":"273.155678ms","start":"2026-10-19T01:51:21.15683Z","end":"2026-10-19T01:51:21.429986Z","steps":["trace[1534273764] 'agreement among raft nodes before linearized reading' (duration: 272.914741ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:51:23.249242Z","caller":"traceutil/trace.go:171","msg":"trace[1013944626] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"109.944099ms","start":"2026-10-19T01:51:23.139267Z","end":"2026-10-19T01:51:23.249211Z","steps":["trace[1013944626] 'process raft request' (duration: 98.895804ms)","trace[1013944626] 'compare' (duration: 10.931571ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:51:49.296524Z","caller":"traceutil/trace.go:171","msg":"trace[514009916] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"300.423638ms","start":"2026-10-19T01:51:48.996075Z","end":"2026-10-19T01:51:49.296498Z","steps":["trace[514009916] 'process raft request' (duration: 222.320078ms)","trace[514009916] 'compare' (duration: 77.938685ms)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:51:49.296681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:51:48.996051Z","time spent":"300.549541ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:51:49.29769Z","caller":"traceutil/trace.go:171","msg":"trace[934756266] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"299.37534ms","start":"2026-10-19T01:51:48.998296Z","end":"2026-10-19T01:51:49.297671Z","steps":["trace[934756266] 'process raft request' (duration: 299.204555ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:51:49.297851Z","caller":"traceutil/trace.go:171","msg":"trace[754588247] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"119.527245ms","start":"2026-10-19T01:51:49.178307Z","end":"2026-10-19T01:51:49.297834Z","steps":["trace[754588247] 'process raft request' (duration: 119.235817ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:51:49.298312Z","caller":"traceutil/trace.go:171","msg":"trace[1596187492] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"302.1179ms","start":"2026-10-19T01:51:48.99616Z","end":"2026-10-19T01:51:49.298278Z","steps":["trace[1596187492] 'process raft request' (duration: 301.165435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:51:49.298391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:51:48.996144Z","time spent":"302.204003ms","remote":"127.0.0.1:41638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:51:49.298567Z","caller":"traceutil/trace.go:171","msg":"trace[2106349933] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"302.155411ms","start":"2026-10-19T01:51:48.996401Z","end":"2026-10-19T01:51:49.298556Z","steps":["trace[2106349933] 'process raft request' (duration: 301.010172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:51:49.298635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:51:48.996387Z","time spent":"302.208222ms","remote":"127.0.0.1:41638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:51:49.298886Z","caller":"traceutil/trace.go:171","msg":"trace[919443057] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"301.677839ms","start":"2026-10-19T01:51:48.997197Z","end":"2026-10-19T01:51:49.298875Z","steps":["trace[919443057] 'process raft request' (duration: 300.266492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:51:49.298945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-19T01:51:48.99718Z","time spent":"301.732901ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-19T01:52:01.920457Z","caller":"traceutil/trace.go:171","msg":"trace[398389421] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"108.196884ms","start":"2026-10-19T01:52:01.81223Z","end":"2026-10-19T01:52:01.920427Z","steps":["trace[398389421] 'process raft request' (duration: 107.99346ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:52:28.429855Z","caller":"traceutil/trace.go:171","msg":"trace[1015040778] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"122.452398ms","start":"2026-10-19T01:52:28.30738Z","end":"2026-10-19T01:52:28.429832Z","steps":["trace[1015040778] 'process raft request' (duration: 122.326595ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:52:29.672499Z","caller":"traceutil/trace.go:171","msg":"trace[840336696] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"146.501899ms","start":"2026-10-19T01:52:29.52597Z","end":"2026-10-19T01:52:29.672472Z","steps":["trace[840336696] 'process raft request' (duration: 146.294034ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:52:29.672678Z","caller":"traceutil/trace.go:171","msg":"trace[267261699] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"130.980791ms","start":"2026-10-19T01:52:29.541686Z","end":"2026-10-19T01:52:29.672667Z","steps":["trace[267261699] 'read index received' (duration: 130.612703ms)","trace[267261699] 'applied index is now lower than readState.Index' (duration: 366.958µs)"],"step_count":2} {"level":"warn","ts":"2026-10-19T01:52:29.672916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.196667ms","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-10-19T01:52:29.672953Z","caller":"traceutil/trace.go:171","msg":"trace[180593452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2135; }","duration":"131.275288ms","start":"2026-10-19T01:52:29.541668Z","end":"2026-10-19T01:52:29.672943Z","steps":["trace[180593452] 'agreement among raft nodes before linearized reading' (duration: 131.073843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:52:29.88493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.954306ms","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-10-19T01:52:29.885134Z","caller":"traceutil/trace.go:171","msg":"trace[2011877904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2137; }","duration":"113.171421ms","start":"2026-10-19T01:52:29.77191Z","end":"2026-10-19T01:52:29.885081Z","steps":["trace[2011877904] 'range keys from in-memory index tree' (duration: 112.777733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:52:29.885006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.550679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-19T01:52:29.885424Z","caller":"traceutil/trace.go:171","msg":"trace[1962028762] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2137; }","duration":"106.008709ms","start":"2026-10-19T01:52:29.779389Z","end":"2026-10-19T01:52:29.885397Z","steps":["trace[1962028762] 'range keys from in-memory index tree' (duration: 105.382897ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:53:58.272453Z","caller":"traceutil/trace.go:171","msg":"trace[698978229] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"186.359534ms","start":"2026-10-19T01:53:58.086066Z","end":"2026-10-19T01:53:58.272426Z","steps":["trace[698978229] 'process raft request' (duration: 119.501371ms)","trace[698978229] 'compare' (duration: 66.65839ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:58:04.731021Z","caller":"traceutil/trace.go:171","msg":"trace[256747989] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"110.876049ms","start":"2026-10-19T01:58:04.620111Z","end":"2026-10-19T01:58:04.730987Z","steps":["trace[256747989] 'process raft request' (duration: 63.984591ms)","trace[256747989] 'compare' (duration: 46.477252ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:58:04.731135Z","caller":"traceutil/trace.go:171","msg":"trace[460243993] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"101.707929ms","start":"2026-10-19T01:58:04.629401Z","end":"2026-10-19T01:58:04.731109Z","steps":["trace[460243993] 'process raft request' (duration: 101.393144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:20.066459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.970905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:58:20.066656Z","caller":"traceutil/trace.go:171","msg":"trace[1362438603] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4869; }","duration":"112.2764ms","start":"2026-10-19T01:58:19.954356Z","end":"2026-10-19T01:58:20.066633Z","steps":["trace[1362438603] 'count revisions from in-memory index tree' (duration: 111.782503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:20.066767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.038717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-10-19T01:58:20.066841Z","caller":"traceutil/trace.go:171","msg":"trace[1177296362] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:4869; }","duration":"158.15491ms","start":"2026-10-19T01:58:19.90867Z","end":"2026-10-19T01:58:20.066825Z","steps":["trace[1177296362] 'range keys from in-memory index tree' (duration: 157.802415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:20.067147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.35068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:131010"} {"level":"info","ts":"2026-10-19T01:58:20.067261Z","caller":"traceutil/trace.go:171","msg":"trace[1469977643] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4869; }","duration":"155.452221ms","start":"2026-10-19T01:58:19.911758Z","end":"2026-10-19T01:58:20.06721Z","steps":["trace[1469977643] 'range keys from in-memory index tree' (duration: 155.029055ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:20.483422Z","caller":"traceutil/trace.go:171","msg":"trace[332459240] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"125.816821ms","start":"2026-10-19T01:58:20.357566Z","end":"2026-10-19T01:58:20.483383Z","steps":["trace[332459240] 'process raft request' (duration: 125.597078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:32.764998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.508401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:58:32.765098Z","caller":"traceutil/trace.go:171","msg":"trace[1726301551] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5042; }","duration":"125.706674ms","start":"2026-10-19T01:58:32.639369Z","end":"2026-10-19T01:58:32.765076Z","steps":["trace[1726301551] 'range keys from in-memory index tree' (duration: 125.389758ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:32.899528Z","caller":"traceutil/trace.go:171","msg":"trace[144333987] linearizableReadLoop","detail":"{readStateIndex:5237; appliedIndex:5236; }","duration":"112.043801ms","start":"2026-10-19T01:58:32.787464Z","end":"2026-10-19T01:58:32.899508Z","steps":["trace[144333987] 'read index received' (duration: 111.804958ms)","trace[144333987] 'applied index is now lower than readState.Index' (duration: 237.733µs)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:58:32.899555Z","caller":"traceutil/trace.go:171","msg":"trace[1821550825] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"120.54705ms","start":"2026-10-19T01:58:32.778986Z","end":"2026-10-19T01:58:32.899533Z","steps":["trace[1821550825] 'process raft request' (duration: 120.373477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:32.899786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.301995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:6011"} {"level":"info","ts":"2026-10-19T01:58:32.899907Z","caller":"traceutil/trace.go:171","msg":"trace[810204525] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5043; }","duration":"112.463326ms","start":"2026-10-19T01:58:32.787426Z","end":"2026-10-19T01:58:32.899889Z","steps":["trace[810204525] 'agreement among raft nodes before linearized reading' (duration: 112.210582ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:33.089344Z","caller":"traceutil/trace.go:171","msg":"trace[1377206846] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"109.800838ms","start":"2026-10-19T01:58:32.979529Z","end":"2026-10-19T01:58:33.08933Z","steps":["trace[1377206846] 'process raft request' (duration: 109.589665ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:33.231681Z","caller":"traceutil/trace.go:171","msg":"trace[2067473334] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"136.92404ms","start":"2026-10-19T01:58:33.094732Z","end":"2026-10-19T01:58:33.231656Z","steps":["trace[2067473334] 'process raft request' (duration: 77.479732ms)","trace[2067473334] 'compare' (duration: 59.256826ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:58:33.231736Z","caller":"traceutil/trace.go:171","msg":"trace[1503287033] linearizableReadLoop","detail":"{readStateIndex:5245; appliedIndex:5240; }","duration":"123.619244ms","start":"2026-10-19T01:58:33.108102Z","end":"2026-10-19T01:58:33.231721Z","steps":["trace[1503287033] 'read index received' (duration: 64.058723ms)","trace[1503287033] 'applied index is now lower than readState.Index' (duration: 59.559911ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:58:33.231817Z","caller":"traceutil/trace.go:171","msg":"trace[357256565] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"137.014731ms","start":"2026-10-19T01:58:33.094793Z","end":"2026-10-19T01:58:33.231807Z","steps":["trace[357256565] 'process raft request' (duration: 136.764609ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:33.231949Z","caller":"traceutil/trace.go:171","msg":"trace[20891869] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"127.681931ms","start":"2026-10-19T01:58:33.104257Z","end":"2026-10-19T01:58:33.231938Z","steps":["trace[20891869] 'process raft request' (duration: 127.420957ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:33.232162Z","caller":"traceutil/trace.go:171","msg":"trace[314832593] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"136.86486ms","start":"2026-10-19T01:58:33.095286Z","end":"2026-10-19T01:58:33.232151Z","steps":["trace[314832593] 'process raft request' (duration: 136.308621ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:58:33.232279Z","caller":"traceutil/trace.go:171","msg":"trace[1010474221] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"135.899596ms","start":"2026-10-19T01:58:33.09637Z","end":"2026-10-19T01:58:33.232269Z","steps":["trace[1010474221] 'process raft request' (duration: 135.271217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:33.232515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.010305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16696"} {"level":"info","ts":"2026-10-19T01:58:33.232563Z","caller":"traceutil/trace.go:171","msg":"trace[588093922] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5051; }","duration":"140.085845ms","start":"2026-10-19T01:58:33.092465Z","end":"2026-10-19T01:58:33.232551Z","steps":["trace[588093922] 'agreement among raft nodes before linearized reading' (duration: 139.72176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:33.232833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.574281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-19T01:58:33.232872Z","caller":"traceutil/trace.go:171","msg":"trace[925141840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5051; }","duration":"142.629232ms","start":"2026-10-19T01:58:33.090233Z","end":"2026-10-19T01:58:33.232862Z","steps":["trace[925141840] 'agreement among raft nodes before linearized reading' (duration: 142.571451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-19T01:58:33.232978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.483518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-19T01:58:33.233025Z","caller":"traceutil/trace.go:171","msg":"trace[1236623155] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5051; }","duration":"122.560447ms","start":"2026-10-19T01:58:33.110452Z","end":"2026-10-19T01:58:33.233012Z","steps":["trace[1236623155] 'agreement among raft nodes before linearized reading' (duration: 122.446666ms)"],"step_count":1} {"level":"info","ts":"2026-10-19T01:59:18.379514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2831} {"level":"warn","ts":"2026-10-19T01:59:18.663533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.097881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-19T01:59:18.663685Z","caller":"traceutil/trace.go:171","msg":"trace[764888125] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"264.110345ms","start":"2026-10-19T01:59:18.399544Z","end":"2026-10-19T01:59:18.663654Z","steps":["trace[764888125] 'process raft request' (duration: 38.68614ms)","trace[764888125] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/secrets/openstack/sh.helm.release.v1.keystone.v1; req_size:72252; } (duration: 224.754226ms)"],"step_count":2} {"level":"info","ts":"2026-10-19T01:59:18.72584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2831,"took":"344.946722ms","hash":3347816837,"current-db-size-bytes":53141504,"current-db-size":"53 MB","current-db-size-in-use-bytes":28684288,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-19T01:59:18.72591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3347816837,"revision":2831,"compact-revision":-1} {"level":"info","ts":"2026-10-19T02:04:18.385403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5433} {"level":"info","ts":"2026-10-19T02:04:18.510209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5433,"took":"123.102169ms","hash":161325286,"current-db-size-bytes":53141504,"current-db-size":"53 MB","current-db-size-in-use-bytes":18440192,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-19T02:04:18.510278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":161325286,"revision":5433,"compact-revision":2831}