{"level":"warn","ts":"2026-09-21T01:53:39.421793Z","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-21T01:53:39.421903Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.153: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.153:2380","--initial-cluster=instance=https://162.253.55.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.153: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-21T01:53:39.421984Z","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-21T01:53:39.421993Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.153:2380"]} {"level":"info","ts":"2026-09-21T01:53:39.42201Z","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-21T01:53:39.422705Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"]} {"level":"info","ts":"2026-09-21T01:53:39.422784Z","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.153:2380"],"listen-peer-urls":["https://162.253.55.153:2380"],"advertise-client-urls":["https://162.253.55.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.153: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-21T01:53:39.427436Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.390632ms"} {"level":"info","ts":"2026-09-21T01:53:39.434683Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"522eb07aa35b671b","cluster-id":"49de17121e7ddda"} {"level":"info","ts":"2026-09-21T01:53:39.434752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=()"} {"level":"info","ts":"2026-09-21T01:53:39.434773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became follower at term 0"} {"level":"info","ts":"2026-09-21T01:53:39.434798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 522eb07aa35b671b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-21T01:53:39.434814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became follower at term 1"} {"level":"info","ts":"2026-09-21T01:53:39.434859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=(5921864600811955995)"} {"level":"warn","ts":"2026-09-21T01:53:39.443432Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-21T01:53:39.444941Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-21T01:53:39.450903Z","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-21T01:53:39.453975Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"522eb07aa35b671b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-21T01:53:39.454202Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"522eb07aa35b671b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-21T01:53:39.454221Z","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-21T01:53:39.454347Z","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-21T01:53:39.454361Z","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-21T01:53:39.455299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=(5921864600811955995)"} {"level":"info","ts":"2026-09-21T01:53:39.455621Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49de17121e7ddda","local-member-id":"522eb07aa35b671b","added-peer-id":"522eb07aa35b671b","added-peer-peer-urls":["https://162.253.55.153:2380"]} {"level":"info","ts":"2026-09-21T01:53:39.461792Z","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-21T01:53:39.462618Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.153:2380"} {"level":"info","ts":"2026-09-21T01:53:39.462656Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.153:2380"} {"level":"info","ts":"2026-09-21T01:53:39.462909Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"522eb07aa35b671b","initial-advertise-peer-urls":["https://162.253.55.153:2380"],"listen-peer-urls":["https://162.253.55.153:2380"],"advertise-client-urls":["https://162.253.55.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-21T01:53:39.46294Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-21T01:53:39.835438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b is starting a new election at term 1"} {"level":"info","ts":"2026-09-21T01:53:39.835569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-21T01:53:39.835764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b received MsgPreVoteResp from 522eb07aa35b671b at term 1"} {"level":"info","ts":"2026-09-21T01:53:39.835804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became candidate at term 2"} {"level":"info","ts":"2026-09-21T01:53:39.835851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b received MsgVoteResp from 522eb07aa35b671b at term 2"} {"level":"info","ts":"2026-09-21T01:53:39.835951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became leader at term 2"} {"level":"info","ts":"2026-09-21T01:53:39.835976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 522eb07aa35b671b elected leader 522eb07aa35b671b at term 2"} {"level":"info","ts":"2026-09-21T01:53:39.838721Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-21T01:53:39.839156Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"522eb07aa35b671b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.153:2379]}","request-path":"/0/members/522eb07aa35b671b/attributes","cluster-id":"49de17121e7ddda","publish-timeout":"7s"} {"level":"info","ts":"2026-09-21T01:53:39.83917Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-21T01:53:39.839357Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-21T01:53:39.841214Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-21T01:53:39.84124Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-21T01:53:39.841299Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49de17121e7ddda","local-member-id":"522eb07aa35b671b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-21T01:53:39.841622Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-21T01:53:39.841663Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-21T01:53:39.841962Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.153:2379"} {"level":"info","ts":"2026-09-21T01:53:39.844322Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-21T01:53:45.515766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.352903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-21T01:53:45.515923Z","caller":"traceutil/trace.go:171","msg":"trace[1373522658] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"270.778566ms","start":"2026-09-21T01:53:45.24512Z","end":"2026-09-21T01:53:45.515899Z","steps":["trace[1373522658] 'process raft request' (duration: 113.73332ms)","trace[1373522658] 'compare' (duration: 156.137832ms)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:53:45.536747Z","caller":"traceutil/trace.go:171","msg":"trace[376457471] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"138.470179ms","start":"2026-09-21T01:53:45.398258Z","end":"2026-09-21T01:53:45.536729Z","steps":["trace[376457471] 'read index received' (duration: 138.407609ms)","trace[376457471] 'applied index is now lower than readState.Index' (duration: 61.69µs)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:53:45.536848Z","caller":"traceutil/trace.go:171","msg":"trace[1134557461] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"186.508406ms","start":"2026-09-21T01:53:45.350325Z","end":"2026-09-21T01:53:45.536833Z","steps":["trace[1134557461] 'process raft request' (duration: 186.244535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:53:45.536962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.70535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-21T01:53:45.537196Z","caller":"traceutil/trace.go:171","msg":"trace[1576311450] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:291; }","duration":"139.014361ms","start":"2026-09-21T01:53:45.398144Z","end":"2026-09-21T01:53:45.537158Z","steps":["trace[1576311450] 'agreement among raft nodes before linearized reading' (duration: 138.73164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:53:45.991474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.001878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-21T01:53:45.991897Z","caller":"traceutil/trace.go:171","msg":"trace[609946242] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"449.298704ms","start":"2026-09-21T01:53:45.542579Z","end":"2026-09-21T01:53:45.991878Z","steps":["trace[609946242] 'process raft request' (duration: 449.255364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:53:45.991976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.542571Z","time spent":"449.379705ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-21T01:53:45.992236Z","caller":"traceutil/trace.go:171","msg":"trace[578785234] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"592.579566ms","start":"2026-09-21T01:53:45.399643Z","end":"2026-09-21T01:53:45.992223Z","steps":["trace[578785234] 'process raft request' (duration: 400.740965ms)","trace[578785234] 'compare' (duration: 190.829706ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:53:45.992292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.399604Z","time spent":"592.666056ms","remote":"127.0.0.1:48712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-21T01:53:45.992296Z","caller":"traceutil/trace.go:171","msg":"trace[624630229] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"464.693098ms","start":"2026-09-21T01:53:45.52757Z","end":"2026-09-21T01:53:45.992263Z","steps":["trace[624630229] 'process raft request' (duration: 464.133535ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:53:45.992322Z","caller":"traceutil/trace.go:171","msg":"trace[1719964456] transaction","detail":"{read_only:false; number_of_response:0; response_revision:292; }","duration":"465.354901ms","start":"2026-09-21T01:53:45.526947Z","end":"2026-09-21T01:53:45.992302Z","steps":["trace[1719964456] 'process raft request' (duration: 464.707118ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:53:45.992329Z","caller":"traceutil/trace.go:171","msg":"trace[2095742171] transaction","detail":"{read_only:false; number_of_response:0; response_revision:292; }","duration":"465.00706ms","start":"2026-09-21T01:53:45.5273Z","end":"2026-09-21T01:53:45.992307Z","steps":["trace[2095742171] 'process raft request' (duration: 464.378267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:53:45.992427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.527291Z","time spent":"465.096861ms","remote":"127.0.0.1:48810","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-21T01:53:45.992451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.526932Z","time spent":"465.451751ms","remote":"127.0.0.1:48810","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-21T01:53:45.992453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.527561Z","time spent":"464.810908ms","remote":"127.0.0.1:48810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-21T01:53:45.992471Z","caller":"traceutil/trace.go:171","msg":"trace[171072650] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"461.959895ms","start":"2026-09-21T01:53:45.530502Z","end":"2026-09-21T01:53:45.992462Z","steps":["trace[171072650] 'process raft request' (duration: 461.277502ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:53:45.992437Z","caller":"traceutil/trace.go:171","msg":"trace[1502058746] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"464.462067ms","start":"2026-09-21T01:53:45.527965Z","end":"2026-09-21T01:53:45.992427Z","steps":["trace[1502058746] 'process raft request' (duration: 463.786614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:53:45.992529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.530491Z","time spent":"462.015046ms","remote":"127.0.0.1:48810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-21T01:53:45.992546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.527939Z","time spent":"464.583718ms","remote":"127.0.0.1:48810","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-21T01:53:45.992535Z","caller":"traceutil/trace.go:171","msg":"trace[1155352213] transaction","detail":"{read_only:false; number_of_response:0; response_revision:292; }","duration":"486.04149ms","start":"2026-09-21T01:53:45.506368Z","end":"2026-09-21T01:53:45.992409Z","steps":["trace[1155352213] 'process raft request' (duration: 485.235296ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:53:45.992651Z","caller":"traceutil/trace.go:171","msg":"trace[1744047455] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:297; }","duration":"455.619036ms","start":"2026-09-21T01:53:45.537003Z","end":"2026-09-21T01:53:45.992622Z","steps":["trace[1744047455] 'read index received' (duration: 263.408062ms)","trace[1744047455] 'applied index is now lower than readState.Index' (duration: 192.209024ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:53:45.992701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.245361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-21T01:53:45.992748Z","caller":"traceutil/trace.go:171","msg":"trace[1273964313] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:295; }","duration":"471.336271ms","start":"2026-09-21T01:53:45.521401Z","end":"2026-09-21T01:53:45.992737Z","steps":["trace[1273964313] 'agreement among raft nodes before linearized reading' (duration: 471.236351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:53:45.992782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.521359Z","time spent":"471.416391ms","remote":"127.0.0.1:48834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-09-21T01:53:45.9928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.321931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-21T01:53:45.992806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.322014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-21T01:53:45.992836Z","caller":"traceutil/trace.go:171","msg":"trace[2005305608] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:295; }","duration":"589.383321ms","start":"2026-09-21T01:53:45.403442Z","end":"2026-09-21T01:53:45.992825Z","steps":["trace[2005305608] 'agreement among raft nodes before linearized reading' (duration: 589.299551ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:53:45.992846Z","caller":"traceutil/trace.go:171","msg":"trace[12076583] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:295; }","duration":"453.379864ms","start":"2026-09-21T01:53:45.539455Z","end":"2026-09-21T01:53:45.992835Z","steps":["trace[12076583] 'agreement among raft nodes before linearized reading' (duration: 453.303394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:53:45.992874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.403434Z","time spent":"589.431802ms","remote":"127.0.0.1:48892","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-21T01:53:45.992875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.539448Z","time spent":"453.420435ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-21T01:53:45.99298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:53:45.506327Z","time spent":"486.431492ms","remote":"127.0.0.1:48796","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-21T01:54:25.836136Z","caller":"traceutil/trace.go:171","msg":"trace[1661348123] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"141.253122ms","start":"2026-09-21T01:54:25.694851Z","end":"2026-09-21T01:54:25.836104Z","steps":["trace[1661348123] 'process raft request' (duration: 140.662478ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:54:26.180808Z","caller":"traceutil/trace.go:171","msg":"trace[143031488] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"457.70854ms","start":"2026-09-21T01:54:25.723074Z","end":"2026-09-21T01:54:26.180782Z","steps":["trace[143031488] 'process raft request' (duration: 449.728425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:54:26.180969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:54:25.723037Z","time spent":"457.84626ms","remote":"127.0.0.1:48682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-21T01:54:26.190495Z","caller":"traceutil/trace.go:171","msg":"trace[196972098] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"238.930072ms","start":"2026-09-21T01:54:25.951535Z","end":"2026-09-21T01:54:26.190465Z","steps":["trace[196972098] 'process raft request' (duration: 238.33681ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:54:26.201668Z","caller":"traceutil/trace.go:171","msg":"trace[101127702] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"221.409616ms","start":"2026-09-21T01:54:25.980184Z","end":"2026-09-21T01:54:26.201594Z","steps":["trace[101127702] 'read index received' (duration: 192.714103ms)","trace[101127702] 'applied index is now lower than readState.Index' (duration: 28.694203ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:54:26.201796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.608697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-21T01:54:26.201835Z","caller":"traceutil/trace.go:171","msg":"trace[1424616306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"221.704538ms","start":"2026-09-21T01:54:25.98012Z","end":"2026-09-21T01:54:26.201825Z","steps":["trace[1424616306] 'agreement among raft nodes before linearized reading' (duration: 221.598128ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:54:30.581437Z","caller":"traceutil/trace.go:171","msg":"trace[174693289] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"133.435914ms","start":"2026-09-21T01:54:30.447973Z","end":"2026-09-21T01:54:30.581409Z","steps":["trace[174693289] 'read index received' (duration: 132.161978ms)","trace[174693289] 'applied index is now lower than readState.Index' (duration: 1.272276ms)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:54:30.581512Z","caller":"traceutil/trace.go:171","msg":"trace[120476289] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"170.062341ms","start":"2026-09-21T01:54:30.411413Z","end":"2026-09-21T01:54:30.581475Z","steps":["trace[120476289] 'process raft request' (duration: 169.223627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:54:30.581712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.883052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-21T01:54:30.58185Z","caller":"traceutil/trace.go:171","msg":"trace[956764015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"119.091262ms","start":"2026-09-21T01:54:30.462691Z","end":"2026-09-21T01:54:30.581782Z","steps":["trace[956764015] 'agreement among raft nodes before linearized reading' (duration: 118.895992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:54:30.582414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.417848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-21T01:54:30.582589Z","caller":"traceutil/trace.go:171","msg":"trace[722582043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"134.551198ms","start":"2026-09-21T01:54:30.447957Z","end":"2026-09-21T01:54:30.582508Z","steps":["trace[722582043] 'agreement among raft nodes before linearized reading' (duration: 133.628505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:54:30.987951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.909075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-21T01:54:30.988093Z","caller":"traceutil/trace.go:171","msg":"trace[166367913] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"379.372462ms","start":"2026-09-21T01:54:30.608698Z","end":"2026-09-21T01:54:30.988071Z","steps":["trace[166367913] 'process raft request' (duration: 157.076615ms)","trace[166367913] 'compare' (duration: 221.777525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:54:30.988356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:54:30.60866Z","time spent":"379.655633ms","remote":"127.0.0.1:48810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-21T01:54:31.002579Z","caller":"traceutil/trace.go:171","msg":"trace[1964715883] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"211.895482ms","start":"2026-09-21T01:54:30.790653Z","end":"2026-09-21T01:54:31.002549Z","steps":["trace[1964715883] 'read index received' (duration: 211.721731ms)","trace[1964715883] 'applied index is now lower than readState.Index' (duration: 172.231µs)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:54:31.002623Z","caller":"traceutil/trace.go:171","msg":"trace[1968848757] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"344.905173ms","start":"2026-09-21T01:54:30.657673Z","end":"2026-09-21T01:54:31.002578Z","steps":["trace[1968848757] 'process raft request' (duration: 344.692262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:54:31.002925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-21T01:54:30.65764Z","time spent":"345.181724ms","remote":"127.0.0.1:48994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-21T01:54:31.002931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.186063ms","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-09-21T01:54:31.003031Z","caller":"traceutil/trace.go:171","msg":"trace[800853435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"212.444644ms","start":"2026-09-21T01:54:30.790558Z","end":"2026-09-21T01:54:31.003003Z","steps":["trace[800853435] 'agreement among raft nodes before linearized reading' (duration: 212.150113ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:08.891027Z","caller":"traceutil/trace.go:171","msg":"trace[301993704] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"192.67705ms","start":"2026-09-21T01:56:08.698303Z","end":"2026-09-21T01:56:08.89098Z","steps":["trace[301993704] 'read index received' (duration: 122.98722ms)","trace[301993704] 'applied index is now lower than readState.Index' (duration: 69.68777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:56:08.891421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.093661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-21T01:56:08.891471Z","caller":"traceutil/trace.go:171","msg":"trace[1284542654] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1909; }","duration":"193.214425ms","start":"2026-09-21T01:56:08.698239Z","end":"2026-09-21T01:56:08.891454Z","steps":["trace[1284542654] 'agreement among raft nodes before linearized reading' (duration: 192.988699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:56:09.269921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.36933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-21T01:56:09.269965Z","caller":"traceutil/trace.go:171","msg":"trace[1232872461] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"101.493064ms","start":"2026-09-21T01:56:09.168446Z","end":"2026-09-21T01:56:09.269939Z","steps":["trace[1232872461] 'read index received' (duration: 31.066279ms)","trace[1232872461] 'applied index is now lower than readState.Index' (duration: 70.425265ms)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:56:09.270019Z","caller":"traceutil/trace.go:171","msg":"trace[2093802905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1910; }","duration":"248.510164ms","start":"2026-09-21T01:56:09.021485Z","end":"2026-09-21T01:56:09.269995Z","steps":["trace[2093802905] 'range keys from in-memory index tree' (duration: 248.153474ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:09.270369Z","caller":"traceutil/trace.go:171","msg":"trace[166323588] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"176.649213ms","start":"2026-09-21T01:56:09.093667Z","end":"2026-09-21T01:56:09.270316Z","steps":["trace[166323588] 'process raft request' (duration: 105.894871ms)","trace[166323588] 'compare' (duration: 70.2413ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:56:09.270534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.014407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6d08680f-b1c5-49b2-8668-a0ef2772dfda\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-21T01:56:09.270634Z","caller":"traceutil/trace.go:171","msg":"trace[1575171728] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6d08680f-b1c5-49b2-8668-a0ef2772dfda; range_end:; response_count:1; response_revision:1911; }","duration":"102.185301ms","start":"2026-09-21T01:56:09.16843Z","end":"2026-09-21T01:56:09.270615Z","steps":["trace[1575171728] 'agreement among raft nodes before linearized reading' (duration: 101.835062ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:21.415942Z","caller":"traceutil/trace.go:171","msg":"trace[1145400131] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"104.756243ms","start":"2026-09-21T01:56:21.311149Z","end":"2026-09-21T01:56:21.415905Z","steps":["trace[1145400131] 'process raft request' (duration: 104.468517ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:21.559271Z","caller":"traceutil/trace.go:171","msg":"trace[322020412] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2097; }","duration":"143.445703ms","start":"2026-09-21T01:56:21.415799Z","end":"2026-09-21T01:56:21.559244Z","steps":["trace[322020412] 'read index received' (duration: 81.294376ms)","trace[322020412] 'applied index is now lower than readState.Index' (duration: 62.149837ms)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:56:21.559467Z","caller":"traceutil/trace.go:171","msg":"trace[851829843] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"137.755867ms","start":"2026-09-21T01:56:21.421666Z","end":"2026-09-21T01:56:21.559422Z","steps":["trace[851829843] 'process raft request' (duration: 137.542081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:56:21.559518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.774079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-21T01:56:21.559428Z","caller":"traceutil/trace.go:171","msg":"trace[1434124208] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"208.033935ms","start":"2026-09-21T01:56:21.351355Z","end":"2026-09-21T01:56:21.559389Z","steps":["trace[1434124208] 'process raft request' (duration: 145.728204ms)","trace[1434124208] 'compare' (duration: 61.979143ms)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:56:21.559574Z","caller":"traceutil/trace.go:171","msg":"trace[707937724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2016; }","duration":"207.910332ms","start":"2026-09-21T01:56:21.351641Z","end":"2026-09-21T01:56:21.559552Z","steps":["trace[707937724] 'agreement among raft nodes before linearized reading' (duration: 207.707878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:56:21.559834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.255537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-21T01:56:21.559914Z","caller":"traceutil/trace.go:171","msg":"trace[1889746840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2016; }","duration":"207.39065ms","start":"2026-09-21T01:56:21.352509Z","end":"2026-09-21T01:56:21.5599Z","steps":["trace[1889746840] 'agreement among raft nodes before linearized reading' (duration: 206.95462ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:25.154448Z","caller":"traceutil/trace.go:171","msg":"trace[1922271062] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"109.61635ms","start":"2026-09-21T01:56:25.044801Z","end":"2026-09-21T01:56:25.154417Z","steps":["trace[1922271062] 'process raft request' (duration: 109.303423ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:25.519768Z","caller":"traceutil/trace.go:171","msg":"trace[1972815221] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"165.069358ms","start":"2026-09-21T01:56:25.35467Z","end":"2026-09-21T01:56:25.51974Z","steps":["trace[1972815221] 'process raft request' (duration: 164.875014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:56:47.88446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.98272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-21T01:56:47.884586Z","caller":"traceutil/trace.go:171","msg":"trace[153853407] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"178.040092ms","start":"2026-09-21T01:56:47.706527Z","end":"2026-09-21T01:56:47.884567Z","steps":["trace[153853407] 'read index received' (duration: 23.794357ms)","trace[153853407] 'applied index is now lower than readState.Index' (duration: 154.244645ms)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:56:47.884671Z","caller":"traceutil/trace.go:171","msg":"trace[1599437686] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"189.241533ms","start":"2026-09-21T01:56:47.695416Z","end":"2026-09-21T01:56:47.884657Z","steps":["trace[1599437686] 'process raft request' (duration: 34.946437ms)","trace[1599437686] 'compare' (duration: 153.805267ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:56:47.884921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.364578ms","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-09-21T01:56:47.884936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.750896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-21T01:56:47.885013Z","caller":"traceutil/trace.go:171","msg":"trace[1055519649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2173; }","duration":"178.48887ms","start":"2026-09-21T01:56:47.706503Z","end":"2026-09-21T01:56:47.884992Z","steps":["trace[1055519649] 'agreement among raft nodes before linearized reading' (duration: 178.267736ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:47.885037Z","caller":"traceutil/trace.go:171","msg":"trace[1052191093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2173; }","duration":"169.92035ms","start":"2026-09-21T01:56:47.71509Z","end":"2026-09-21T01:56:47.88501Z","steps":["trace[1052191093] 'agreement among raft nodes before linearized reading' (duration: 169.612064ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:48.110609Z","caller":"traceutil/trace.go:171","msg":"trace[372896692] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"127.723425ms","start":"2026-09-21T01:56:47.982871Z","end":"2026-09-21T01:56:48.110595Z","steps":["trace[372896692] 'read index received' (duration: 84.59847ms)","trace[372896692] 'applied index is now lower than readState.Index' (duration: 43.124195ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:56:48.11069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.809577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-21T01:56:48.11071Z","caller":"traceutil/trace.go:171","msg":"trace[599890795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2178; }","duration":"127.873329ms","start":"2026-09-21T01:56:47.98283Z","end":"2026-09-21T01:56:48.110704Z","steps":["trace[599890795] 'agreement among raft nodes before linearized reading' (duration: 127.818938ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:48.110858Z","caller":"traceutil/trace.go:171","msg":"trace[357731371] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"146.780253ms","start":"2026-09-21T01:56:47.964069Z","end":"2026-09-21T01:56:48.11085Z","steps":["trace[357731371] 'process raft request' (duration: 103.372863ms)","trace[357731371] 'compare' (duration: 43.088094ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T01:56:48.41029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.697396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-21T01:56:48.410396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.498514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-21T01:56:48.410404Z","caller":"traceutil/trace.go:171","msg":"trace[1322115941] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2178; }","duration":"161.83773ms","start":"2026-09-21T01:56:48.248539Z","end":"2026-09-21T01:56:48.410377Z","steps":["trace[1322115941] 'count revisions from in-memory index tree' (duration: 161.616985ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:56:48.410457Z","caller":"traceutil/trace.go:171","msg":"trace[209979955] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2178; }","duration":"190.595287ms","start":"2026-09-21T01:56:48.219848Z","end":"2026-09-21T01:56:48.410444Z","steps":["trace[209979955] 'count revisions from in-memory index tree' (duration: 190.425842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:56:49.042202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.232197ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-21T01:56:49.272556Z","caller":"traceutil/trace.go:171","msg":"trace[833012431] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"169.195218ms","start":"2026-09-21T01:56:49.10333Z","end":"2026-09-21T01:56:49.272525Z","steps":["trace[833012431] 'process raft request' (duration: 168.973034ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:57:46.053299Z","caller":"traceutil/trace.go:171","msg":"trace[1415831825] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"195.662655ms","start":"2026-09-21T01:57:45.85761Z","end":"2026-09-21T01:57:46.053273Z","steps":["trace[1415831825] 'process raft request' (duration: 195.487332ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:57:46.076703Z","caller":"traceutil/trace.go:171","msg":"trace[916486837] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"191.2274ms","start":"2026-09-21T01:57:45.885426Z","end":"2026-09-21T01:57:46.076653Z","steps":["trace[916486837] 'process raft request' (duration: 190.841154ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T01:58:17.863699Z","caller":"traceutil/trace.go:171","msg":"trace[759831194] linearizableReadLoop","detail":"{readStateIndex:2875; appliedIndex:2874; }","duration":"107.459203ms","start":"2026-09-21T01:58:17.75621Z","end":"2026-09-21T01:58:17.86367Z","steps":["trace[759831194] 'read index received' (duration: 107.22173ms)","trace[759831194] 'applied index is now lower than readState.Index' (duration: 236.663µs)"],"step_count":2} {"level":"info","ts":"2026-09-21T01:58:17.863744Z","caller":"traceutil/trace.go:171","msg":"trace[2103716602] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"182.333503ms","start":"2026-09-21T01:58:17.681375Z","end":"2026-09-21T01:58:17.863709Z","steps":["trace[2103716602] 'process raft request' (duration: 182.128931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T01:58:17.863957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.731967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-21T01:58:17.863997Z","caller":"traceutil/trace.go:171","msg":"trace[128849447] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2766; }","duration":"107.834508ms","start":"2026-09-21T01:58:17.756151Z","end":"2026-09-21T01:58:17.863986Z","steps":["trace[128849447] 'agreement among raft nodes before linearized reading' (duration: 107.631925ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:02:43.338658Z","caller":"traceutil/trace.go:171","msg":"trace[1969659197] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"127.791084ms","start":"2026-09-21T02:02:43.210836Z","end":"2026-09-21T02:02:43.338627Z","steps":["trace[1969659197] 'process raft request' (duration: 125.54952ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:02:43.339661Z","caller":"traceutil/trace.go:171","msg":"trace[1071685031] linearizableReadLoop","detail":"{readStateIndex:4891; appliedIndex:4889; }","duration":"124.539946ms","start":"2026-09-21T02:02:43.215105Z","end":"2026-09-21T02:02:43.339645Z","steps":["trace[1071685031] 'read index received' (duration: 121.307067ms)","trace[1071685031] 'applied index is now lower than readState.Index' (duration: 3.231709ms)"],"step_count":2} {"level":"warn","ts":"2026-09-21T02:02:43.339757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.625988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-21T02:02:43.33985Z","caller":"traceutil/trace.go:171","msg":"trace[1380591747] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:0; response_revision:4704; }","duration":"124.689ms","start":"2026-09-21T02:02:43.215094Z","end":"2026-09-21T02:02:43.339783Z","steps":["trace[1380591747] 'agreement among raft nodes before linearized reading' (duration: 124.610328ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:02:43.340077Z","caller":"traceutil/trace.go:171","msg":"trace[1913098890] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"125.580872ms","start":"2026-09-21T02:02:43.214466Z","end":"2026-09-21T02:02:43.340047Z","steps":["trace[1913098890] 'process raft request' (duration: 125.100654ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:02:48.589604Z","caller":"traceutil/trace.go:171","msg":"trace[904504293] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"141.417521ms","start":"2026-09-21T02:02:48.448156Z","end":"2026-09-21T02:02:48.589574Z","steps":["trace[904504293] 'process raft request' (duration: 141.205567ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:02:54.775658Z","caller":"traceutil/trace.go:171","msg":"trace[251365980] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"132.050357ms","start":"2026-09-21T02:02:54.64358Z","end":"2026-09-21T02:02:54.77563Z","steps":["trace[251365980] 'process raft request' (duration: 131.876844ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:02:55.137945Z","caller":"traceutil/trace.go:171","msg":"trace[443800874] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"169.974054ms","start":"2026-09-21T02:02:54.967943Z","end":"2026-09-21T02:02:55.137918Z","steps":["trace[443800874] 'process raft request' (duration: 169.801411ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:02:55.138351Z","caller":"traceutil/trace.go:171","msg":"trace[841206438] linearizableReadLoop","detail":"{readStateIndex:5132; appliedIndex:5130; }","duration":"155.503026ms","start":"2026-09-21T02:02:54.982831Z","end":"2026-09-21T02:02:55.138334Z","steps":["trace[841206438] 'read index received' (duration: 154.211897ms)","trace[841206438] 'applied index is now lower than readState.Index' (duration: 1.289929ms)"],"step_count":2} {"level":"info","ts":"2026-09-21T02:02:55.138515Z","caller":"traceutil/trace.go:171","msg":"trace[737649034] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"161.82123ms","start":"2026-09-21T02:02:54.976681Z","end":"2026-09-21T02:02:55.138502Z","steps":["trace[737649034] 'process raft request' (duration: 161.309073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-21T02:02:55.138556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.642768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-21T02:02:55.138619Z","caller":"traceutil/trace.go:171","msg":"trace[708445294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4937; }","duration":"155.853541ms","start":"2026-09-21T02:02:54.982754Z","end":"2026-09-21T02:02:55.138607Z","steps":["trace[708445294] 'agreement among raft nodes before linearized reading' (duration: 155.671388ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:03:00.531184Z","caller":"traceutil/trace.go:171","msg":"trace[665813645] linearizableReadLoop","detail":"{readStateIndex:5247; appliedIndex:5246; }","duration":"173.632335ms","start":"2026-09-21T02:03:00.35731Z","end":"2026-09-21T02:03:00.530943Z","steps":["trace[665813645] 'read index received' (duration: 173.438242ms)","trace[665813645] 'applied index is now lower than readState.Index' (duration: 192.683µs)"],"step_count":2} {"level":"warn","ts":"2026-09-21T02:03:00.531408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.075821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e24a0303-e2f8-4cdf-9da0-6e74768c6da4\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-09-21T02:03:00.531549Z","caller":"traceutil/trace.go:171","msg":"trace[1454493545] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e24a0303-e2f8-4cdf-9da0-6e74768c6da4; range_end:; response_count:1; response_revision:5051; }","duration":"174.281605ms","start":"2026-09-21T02:03:00.357254Z","end":"2026-09-21T02:03:00.531536Z","steps":["trace[1454493545] 'agreement among raft nodes before linearized reading' (duration: 174.024321ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:03:00.531762Z","caller":"traceutil/trace.go:171","msg":"trace[1157431886] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"191.568632ms","start":"2026-09-21T02:03:00.340128Z","end":"2026-09-21T02:03:00.531696Z","steps":["trace[1157431886] 'process raft request' (duration: 190.664918ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:03:11.459164Z","caller":"traceutil/trace.go:171","msg":"trace[95387013] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"106.133574ms","start":"2026-09-21T02:03:11.353Z","end":"2026-09-21T02:03:11.459134Z","steps":["trace[95387013] 'process raft request' (duration: 96.017635ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:03:39.964784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2882} {"level":"info","ts":"2026-09-21T02:03:40.045024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2882,"took":"78.700823ms","hash":3236684422,"current-db-size-bytes":51789824,"current-db-size":"52 MB","current-db-size-in-use-bytes":28053504,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-21T02:03:40.04525Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3236684422,"revision":2882,"compact-revision":-1} {"level":"info","ts":"2026-09-21T02:06:51.368298Z","caller":"traceutil/trace.go:171","msg":"trace[1996262106] transaction","detail":"{read_only:false; response_revision:6779; number_of_response:1; }","duration":"109.23544ms","start":"2026-09-21T02:06:51.259041Z","end":"2026-09-21T02:06:51.368276Z","steps":["trace[1996262106] 'process raft request' (duration: 109.093227ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:06:51.368589Z","caller":"traceutil/trace.go:171","msg":"trace[1180314027] transaction","detail":"{read_only:false; response_revision:6780; number_of_response:1; }","duration":"109.466782ms","start":"2026-09-21T02:06:51.25911Z","end":"2026-09-21T02:06:51.368577Z","steps":["trace[1180314027] 'process raft request' (duration: 109.110187ms)"],"step_count":1} {"level":"info","ts":"2026-09-21T02:08:39.970346Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5368} {"level":"info","ts":"2026-09-21T02:08:40.084751Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5368,"took":"113.102809ms","hash":96952955,"current-db-size-bytes":51789824,"current-db-size":"52 MB","current-db-size-in-use-bytes":18923520,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-21T02:08:40.084819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":96952955,"revision":5368,"compact-revision":2882}