{"level":"warn","ts":"2026-09-17T19:32:08.717568Z","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-17T19:32:08.717682Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.166: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.166:2380","--initial-cluster=instance=https://162.253.55.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.166: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-17T19:32:08.717767Z","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-17T19:32:08.717778Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-09-17T19:32:08.717804Z","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-17T19:32:08.718581Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"]} {"level":"info","ts":"2026-09-17T19:32:08.718687Z","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.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.166: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-17T19:32:08.723682Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.779112ms"} {"level":"info","ts":"2026-09-17T19:32:08.738117Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7481851e1bcdf3c","cluster-id":"bb367d182eb83f54"} {"level":"info","ts":"2026-09-17T19:32:08.738293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=()"} {"level":"info","ts":"2026-09-17T19:32:08.738376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 0"} {"level":"info","ts":"2026-09-17T19:32:08.73841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7481851e1bcdf3c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-17T19:32:08.738432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 1"} {"level":"info","ts":"2026-09-17T19:32:08.738563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"warn","ts":"2026-09-17T19:32:08.746401Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-17T19:32:08.748086Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-17T19:32:08.74937Z","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-17T19:32:08.751378Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7481851e1bcdf3c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-17T19:32:08.751734Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7481851e1bcdf3c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-17T19:32:08.751931Z","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-17T19:32:08.753346Z","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-17T19:32:08.753371Z","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-17T19:32:08.7568Z","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-17T19:32:08.756923Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-09-17T19:32:08.757016Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-09-17T19:32:08.757475Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7481851e1bcdf3c","initial-advertise-peer-urls":["https://162.253.55.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-17T19:32:08.757557Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-17T19:32:08.758168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"info","ts":"2026-09-17T19:32:08.758305Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","added-peer-id":"b7481851e1bcdf3c","added-peer-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-09-17T19:32:09.239694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c is starting a new election at term 1"} {"level":"info","ts":"2026-09-17T19:32:09.239764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became pre-candidate at term 1"} {"level":"info","ts":"2026-09-17T19:32:09.239808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgPreVoteResp from b7481851e1bcdf3c at term 1"} {"level":"info","ts":"2026-09-17T19:32:09.239836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became candidate at term 2"} {"level":"info","ts":"2026-09-17T19:32:09.239861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgVoteResp from b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-09-17T19:32:09.239922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became leader at term 2"} {"level":"info","ts":"2026-09-17T19:32:09.239945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7481851e1bcdf3c elected leader b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-09-17T19:32:09.241307Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:32:09.24257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T19:32:09.242612Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T19:32:09.242562Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7481851e1bcdf3c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.166:2379]}","request-path":"/0/members/b7481851e1bcdf3c/attributes","cluster-id":"bb367d182eb83f54","publish-timeout":"7s"} {"level":"info","ts":"2026-09-17T19:32:09.242877Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-17T19:32:09.242928Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-17T19:32:09.243228Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:32:09.243344Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:32:09.243378Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T19:32:09.247593Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-17T19:32:09.249848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.166:2379"} {"level":"info","ts":"2026-09-17T19:32:14.139073Z","caller":"traceutil/trace.go:171","msg":"trace[631341158] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"154.964161ms","start":"2026-09-17T19:32:13.984077Z","end":"2026-09-17T19:32:14.139041Z","steps":["trace[631341158] 'process raft request' (duration: 154.769058ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:32:14.795733Z","caller":"traceutil/trace.go:171","msg":"trace[1203565748] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"684.918212ms","start":"2026-09-17T19:32:14.110792Z","end":"2026-09-17T19:32:14.79571Z","steps":["trace[1203565748] 'read index received' (duration: 28.428888ms)","trace[1203565748] 'applied index is now lower than readState.Index' (duration: 656.488244ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:32:14.795787Z","caller":"traceutil/trace.go:171","msg":"trace[1016796064] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"685.051054ms","start":"2026-09-17T19:32:14.110698Z","end":"2026-09-17T19:32:14.795749Z","steps":["trace[1016796064] 'process raft request' (duration: 684.901002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:32:14.796051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.189446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-17T19:32:14.797032Z","caller":"traceutil/trace.go:171","msg":"trace[941458245] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:268; }","duration":"686.207122ms","start":"2026-09-17T19:32:14.110768Z","end":"2026-09-17T19:32:14.796975Z","steps":["trace[941458245] 'agreement among raft nodes before linearized reading' (duration: 685.032964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:32:14.797163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.110761Z","time spent":"686.374844ms","remote":"127.0.0.1:57838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-17T19:32:14.797409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.110673Z","time spent":"685.406879ms","remote":"127.0.0.1:57484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-17T19:32:14.80356Z","caller":"traceutil/trace.go:171","msg":"trace[1572722276] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"660.25792ms","start":"2026-09-17T19:32:14.143282Z","end":"2026-09-17T19:32:14.80354Z","steps":["trace[1572722276] 'process raft request' (duration: 660.039946ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:32:14.80363Z","caller":"traceutil/trace.go:171","msg":"trace[2040932026] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"598.420721ms","start":"2026-09-17T19:32:14.205191Z","end":"2026-09-17T19:32:14.803612Z","steps":["trace[2040932026] 'process raft request' (duration: 598.259798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:32:14.803671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.143257Z","time spent":"660.360751ms","remote":"127.0.0.1:57776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:32:14.803703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.205163Z","time spent":"598.505642ms","remote":"127.0.0.1:57580","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-17T19:32:14.803747Z","caller":"traceutil/trace.go:171","msg":"trace[95296297] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"567.121999ms","start":"2026-09-17T19:32:14.236607Z","end":"2026-09-17T19:32:14.803729Z","steps":["trace[95296297] 'process raft request' (duration: 566.879466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:32:14.80385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.236573Z","time spent":"567.233412ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-17T19:32:14.804441Z","caller":"traceutil/trace.go:171","msg":"trace[523942577] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"567.662788ms","start":"2026-09-17T19:32:14.236757Z","end":"2026-09-17T19:32:14.804419Z","steps":["trace[523942577] 'process raft request' (duration: 566.762234ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:32:14.804494Z","caller":"traceutil/trace.go:171","msg":"trace[1948699234] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"567.709929ms","start":"2026-09-17T19:32:14.236754Z","end":"2026-09-17T19:32:14.804464Z","steps":["trace[1948699234] 'process raft request' (duration: 567.555196ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:32:14.804614Z","caller":"traceutil/trace.go:171","msg":"trace[2059495245] transaction","detail":"{read_only:false; number_of_response:0; response_revision:272; }","duration":"567.7846ms","start":"2026-09-17T19:32:14.236822Z","end":"2026-09-17T19:32:14.804606Z","steps":["trace[2059495245] 'process raft request' (duration: 567.631417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:32:14.804649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.236726Z","time spent":"567.856001ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:32:14.804672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.236811Z","time spent":"567.83337ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:32:14.804692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.236727Z","time spent":"567.819201ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-17T19:32:14.80451Z","caller":"traceutil/trace.go:171","msg":"trace[980344371] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"567.721519ms","start":"2026-09-17T19:32:14.236775Z","end":"2026-09-17T19:32:14.804496Z","steps":["trace[980344371] 'process raft request' (duration: 567.614697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:32:14.804956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.236754Z","time spent":"568.177366ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T19:32:14.805346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.013926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:32:14.805394Z","caller":"traceutil/trace.go:171","msg":"trace[1543165584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:272; }","duration":"201.095977ms","start":"2026-09-17T19:32:14.604282Z","end":"2026-09-17T19:32:14.805378Z","steps":["trace[1543165584] 'agreement among raft nodes before linearized reading' (duration: 201.028476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:32:14.805533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.536804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:32:14.805572Z","caller":"traceutil/trace.go:171","msg":"trace[1117369800] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:272; }","duration":"694.633125ms","start":"2026-09-17T19:32:14.110928Z","end":"2026-09-17T19:32:14.805561Z","steps":["trace[1117369800] 'agreement among raft nodes before linearized reading' (duration: 694.578364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:32:14.805604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:32:14.110897Z","time spent":"694.698407ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-09-17T19:33:07.748332Z","caller":"traceutil/trace.go:171","msg":"trace[122721226] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"116.941966ms","start":"2026-09-17T19:33:07.631372Z","end":"2026-09-17T19:33:07.748314Z","steps":["trace[122721226] 'process raft request' (duration: 116.835332ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:33:07.754293Z","caller":"traceutil/trace.go:171","msg":"trace[1629672853] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"107.245756ms","start":"2026-09-17T19:33:07.647008Z","end":"2026-09-17T19:33:07.754254Z","steps":["trace[1629672853] 'process raft request' (duration: 106.844711ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:33:07.754515Z","caller":"traceutil/trace.go:171","msg":"trace[649614435] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"107.308268ms","start":"2026-09-17T19:33:07.647193Z","end":"2026-09-17T19:33:07.754501Z","steps":["trace[649614435] 'process raft request' (duration: 106.884052ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:33:07.754424Z","caller":"traceutil/trace.go:171","msg":"trace[704142437] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"107.014348ms","start":"2026-09-17T19:33:07.647372Z","end":"2026-09-17T19:33:07.754386Z","steps":["trace[704142437] 'process raft request' (duration: 106.768979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:33:15.920154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403113628s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:33:15.920539Z","caller":"traceutil/trace.go:171","msg":"trace[1833306596] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.565909848s","start":"2026-09-17T19:33:14.354588Z","end":"2026-09-17T19:33:15.920498Z","steps":["trace[1833306596] 'process raft request' (duration: 162.124568ms)","trace[1833306596] 'compare' (duration: 1.402989133s)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:33:15.920744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:14.354566Z","time spent":"1.566079274s","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:33:15.920906Z","caller":"traceutil/trace.go:171","msg":"trace[927196854] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"1.341795039s","start":"2026-09-17T19:33:14.579077Z","end":"2026-09-17T19:33:15.920872Z","steps":["trace[927196854] 'read index received' (duration: 55.752µs)","trace[927196854] 'applied index is now lower than readState.Index' (duration: 1.341736377s)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:33:15.921062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341954004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-17T19:33:15.921129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159051596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:33:15.921172Z","caller":"traceutil/trace.go:171","msg":"trace[328134589] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:686; }","duration":"1.342073719s","start":"2026-09-17T19:33:14.579066Z","end":"2026-09-17T19:33:15.921139Z","steps":["trace[328134589] 'agreement among raft nodes before linearized reading' (duration: 1.341885793s)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:33:15.92118Z","caller":"traceutil/trace.go:171","msg":"trace[1610400122] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:687; }","duration":"1.159143649s","start":"2026-09-17T19:33:14.762021Z","end":"2026-09-17T19:33:15.921164Z","steps":["trace[1610400122] 'agreement among raft nodes before linearized reading' (duration: 1.159045936s)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:33:15.921222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:14.762Z","time spent":"1.159213412s","remote":"127.0.0.1:57828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:33:15.921229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:14.579015Z","time spent":"1.342199963s","remote":"127.0.0.1:57940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:33:15.921391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.393334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-17T19:33:15.921466Z","caller":"traceutil/trace.go:171","msg":"trace[814657110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"616.487056ms","start":"2026-09-17T19:33:15.304949Z","end":"2026-09-17T19:33:15.921437Z","steps":["trace[814657110] 'agreement among raft nodes before linearized reading' (duration: 616.254529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:33:15.921471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.051196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-17T19:33:15.921505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:15.304936Z","time spent":"616.559739ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-17T19:33:15.921542Z","caller":"traceutil/trace.go:171","msg":"trace[147926751] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:687; }","duration":"660.190371ms","start":"2026-09-17T19:33:15.261337Z","end":"2026-09-17T19:33:15.921527Z","steps":["trace[147926751] 'agreement among raft nodes before linearized reading' (duration: 660.038747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:33:15.921547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.600902ms","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-17T19:33:15.921577Z","caller":"traceutil/trace.go:171","msg":"trace[851571658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"727.653803ms","start":"2026-09-17T19:33:15.193914Z","end":"2026-09-17T19:33:15.921568Z","steps":["trace[851571658] 'agreement among raft nodes before linearized reading' (duration: 727.520798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:33:15.921579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:15.261314Z","time spent":"660.255584ms","remote":"127.0.0.1:57956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":59,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:33:15.921602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:15.193904Z","time spent":"727.691075ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-17T19:33:15.921721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.687758ms","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-17T19:33:15.921746Z","caller":"traceutil/trace.go:171","msg":"trace[1135369095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"738.75275ms","start":"2026-09-17T19:33:15.182984Z","end":"2026-09-17T19:33:15.921737Z","steps":["trace[1135369095] 'agreement among raft nodes before linearized reading' (duration: 738.678827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:33:15.92175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.219389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-17T19:33:15.921769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:15.182939Z","time spent":"738.824853ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-17T19:33:15.921786Z","caller":"traceutil/trace.go:171","msg":"trace[273559525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"639.301292ms","start":"2026-09-17T19:33:15.282473Z","end":"2026-09-17T19:33:15.921775Z","steps":["trace[273559525] 'agreement among raft nodes before linearized reading' (duration: 639.157887ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:33:15.921773Z","caller":"traceutil/trace.go:171","msg":"trace[1247746682] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.187805718s","start":"2026-09-17T19:33:14.733949Z","end":"2026-09-17T19:33:15.921755Z","steps":["trace[1247746682] 'process raft request' (duration: 1.186587247s)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:33:15.921813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:15.28245Z","time spent":"639.357064ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-17T19:33:15.921931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.043853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:33:15.921958Z","caller":"traceutil/trace.go:171","msg":"trace[1301542191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"591.115286ms","start":"2026-09-17T19:33:15.330835Z","end":"2026-09-17T19:33:15.92195Z","steps":["trace[1301542191] 'agreement among raft nodes before linearized reading' (duration: 591.075805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:33:15.92198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:15.330823Z","time spent":"591.152297ms","remote":"127.0.0.1:57400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-17T19:33:15.92192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:33:14.73392Z","time spent":"1.187917202s","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:34:06.506866Z","caller":"traceutil/trace.go:171","msg":"trace[69790183] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"218.787034ms","start":"2026-09-17T19:34:06.288046Z","end":"2026-09-17T19:34:06.506833Z","steps":["trace[69790183] 'read index received' (duration: 218.693842ms)","trace[69790183] 'applied index is now lower than readState.Index' (duration: 91.362µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:34:06.506993Z","caller":"traceutil/trace.go:171","msg":"trace[1402171524] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"262.369465ms","start":"2026-09-17T19:34:06.24459Z","end":"2026-09-17T19:34:06.506959Z","steps":["trace[1402171524] 'process raft request' (duration: 262.081559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:06.50798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.796759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:34:06.508172Z","caller":"traceutil/trace.go:171","msg":"trace[520500693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"176.021144ms","start":"2026-09-17T19:34:06.332086Z","end":"2026-09-17T19:34:06.508107Z","steps":["trace[520500693] 'agreement among raft nodes before linearized reading' (duration: 175.732717ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:06.508275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.169249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-17T19:34:06.508363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.543793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-17T19:34:06.508495Z","caller":"traceutil/trace.go:171","msg":"trace[1275855178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"220.446436ms","start":"2026-09-17T19:34:06.287953Z","end":"2026-09-17T19:34:06.508399Z","steps":["trace[1275855178] 'agreement among raft nodes before linearized reading' (duration: 219.02902ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:34:06.508552Z","caller":"traceutil/trace.go:171","msg":"trace[735200431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"217.737259ms","start":"2026-09-17T19:34:06.290748Z","end":"2026-09-17T19:34:06.508485Z","steps":["trace[735200431] 'agreement among raft nodes before linearized reading' (duration: 217.023111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:06.508721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.973909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:34:06.50876Z","caller":"traceutil/trace.go:171","msg":"trace[1366506055] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1270; }","duration":"193.096862ms","start":"2026-09-17T19:34:06.315654Z","end":"2026-09-17T19:34:06.508751Z","steps":["trace[1366506055] 'agreement among raft nodes before linearized reading' (duration: 191.390059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:06.509318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.711763ms","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-17T19:34:06.509376Z","caller":"traceutil/trace.go:171","msg":"trace[1574165170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"218.791855ms","start":"2026-09-17T19:34:06.290571Z","end":"2026-09-17T19:34:06.509363Z","steps":["trace[1574165170] 'agreement among raft nodes before linearized reading' (duration: 216.448346ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:34:07.029676Z","caller":"traceutil/trace.go:171","msg":"trace[1956672491] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"512.677226ms","start":"2026-09-17T19:34:06.516972Z","end":"2026-09-17T19:34:07.029649Z","steps":["trace[1956672491] 'process raft request' (duration: 512.369989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:07.029915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:34:06.516904Z","time spent":"512.844551ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:34:07.047978Z","caller":"traceutil/trace.go:171","msg":"trace[461606320] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"526.557542ms","start":"2026-09-17T19:34:06.521391Z","end":"2026-09-17T19:34:07.047948Z","steps":["trace[461606320] 'process raft request' (duration: 526.365417ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:34:07.048331Z","caller":"traceutil/trace.go:171","msg":"trace[26481784] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"289.615272ms","start":"2026-09-17T19:34:06.758684Z","end":"2026-09-17T19:34:07.048299Z","steps":["trace[26481784] 'process raft request' (duration: 289.485369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:07.048569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:34:06.521303Z","time spent":"526.774067ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:34:07.048603Z","caller":"traceutil/trace.go:171","msg":"trace[1341272560] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"527.037604ms","start":"2026-09-17T19:34:06.521488Z","end":"2026-09-17T19:34:07.048525Z","steps":["trace[1341272560] 'process raft request' (duration: 526.416878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:07.048991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:34:06.521477Z","time spent":"527.359781ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:34:56.932994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.58595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:34:56.933117Z","caller":"traceutil/trace.go:171","msg":"trace[361278858] linearizableReadLoop","detail":"{readStateIndex:2018; appliedIndex:2017; }","duration":"366.315579ms","start":"2026-09-17T19:34:56.566784Z","end":"2026-09-17T19:34:56.9331Z","steps":["trace[361278858] 'read index received' (duration: 208.466543ms)","trace[361278858] 'applied index is now lower than readState.Index' (duration: 157.847546ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:34:56.933215Z","caller":"traceutil/trace.go:171","msg":"trace[1706747378] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"495.516201ms","start":"2026-09-17T19:34:56.437687Z","end":"2026-09-17T19:34:56.933203Z","steps":["trace[1706747378] 'process raft request' (duration: 337.625104ms)","trace[1706747378] 'compare' (duration: 156.612031ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:34:56.93328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:34:56.437663Z","time spent":"495.583773ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:34:56.933449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.871913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:34:56.933582Z","caller":"traceutil/trace.go:171","msg":"trace[2045281338] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1942; }","duration":"313.054707ms","start":"2026-09-17T19:34:56.620501Z","end":"2026-09-17T19:34:56.933555Z","steps":["trace[2045281338] 'agreement among raft nodes before linearized reading' (duration: 312.870243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:56.933634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.84586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62e28a99-fc7b-4cc9-aba5-ba4f3a8d9e14\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-17T19:34:56.933683Z","caller":"traceutil/trace.go:171","msg":"trace[676200839] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62e28a99-fc7b-4cc9-aba5-ba4f3a8d9e14; range_end:; response_count:1; response_revision:1942; }","duration":"366.918142ms","start":"2026-09-17T19:34:56.566749Z","end":"2026-09-17T19:34:56.933667Z","steps":["trace[676200839] 'agreement among raft nodes before linearized reading' (duration: 366.82205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:34:56.933727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:34:56.56673Z","time spent":"366.979653ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62e28a99-fc7b-4cc9-aba5-ba4f3a8d9e14\" "} {"level":"warn","ts":"2026-09-17T19:34:56.934026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:34:56.620482Z","time spent":"313.519066ms","remote":"127.0.0.1:58844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-09-17T19:34:57.386469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.982805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:34:57.386642Z","caller":"traceutil/trace.go:171","msg":"trace[1872942867] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"443.827322ms","start":"2026-09-17T19:34:56.942795Z","end":"2026-09-17T19:34:57.386622Z","steps":["trace[1872942867] 'process raft request' (duration: 163.605242ms)","trace[1872942867] 'compare' (duration: 279.686039ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:34:57.386705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:34:56.942774Z","time spent":"443.900524ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:35:11.385974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.048097ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:35:11.386168Z","caller":"traceutil/trace.go:171","msg":"trace[1824126378] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2065; }","duration":"198.266781ms","start":"2026-09-17T19:35:11.187877Z","end":"2026-09-17T19:35:11.386144Z","steps":["trace[1824126378] 'range keys from in-memory index tree' (duration: 198.024296ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:35:11.386789Z","caller":"traceutil/trace.go:171","msg":"trace[662948923] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"303.645137ms","start":"2026-09-17T19:35:11.083109Z","end":"2026-09-17T19:35:11.386754Z","steps":["trace[662948923] 'process raft request' (duration: 303.156127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:11.386989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:35:11.083087Z","time spent":"303.794689ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:35:11.88039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.2767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-17T19:35:11.880469Z","caller":"traceutil/trace.go:171","msg":"trace[1569018311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2074; }","duration":"169.399713ms","start":"2026-09-17T19:35:11.71105Z","end":"2026-09-17T19:35:11.88045Z","steps":["trace[1569018311] 'range keys from in-memory index tree' (duration: 169.071745ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:35:27.598838Z","caller":"traceutil/trace.go:171","msg":"trace[1782621551] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"158.848754ms","start":"2026-09-17T19:35:27.439941Z","end":"2026-09-17T19:35:27.598789Z","steps":["trace[1782621551] 'process raft request' (duration: 158.582329ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:35:28.163775Z","caller":"traceutil/trace.go:171","msg":"trace[338846452] linearizableReadLoop","detail":"{readStateIndex:2260; appliedIndex:2259; }","duration":"165.671965ms","start":"2026-09-17T19:35:27.998072Z","end":"2026-09-17T19:35:28.163744Z","steps":["trace[338846452] 'read index received' (duration: 120.374919ms)","trace[338846452] 'applied index is now lower than readState.Index' (duration: 45.295666ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:35:28.164074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.165259ms","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-17T19:35:28.164152Z","caller":"traceutil/trace.go:171","msg":"trace[1969126309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2175; }","duration":"193.312852ms","start":"2026-09-17T19:35:27.970822Z","end":"2026-09-17T19:35:28.164135Z","steps":["trace[1969126309] 'agreement among raft nodes before linearized reading' (duration: 193.070997ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:35:28.164352Z","caller":"traceutil/trace.go:171","msg":"trace[969016838] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"166.272826ms","start":"2026-09-17T19:35:27.99763Z","end":"2026-09-17T19:35:28.163903Z","steps":["trace[969016838] 'process raft request' (duration: 120.839149ms)","trace[969016838] 'compare' (duration: 45.144832ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:35:28.199313Z","caller":"traceutil/trace.go:171","msg":"trace[1690397849] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"194.022635ms","start":"2026-09-17T19:35:28.005253Z","end":"2026-09-17T19:35:28.199276Z","steps":["trace[1690397849] 'process raft request' (duration: 193.77593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:28.813491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.876262ms","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":"warn","ts":"2026-09-17T19:35:28.813543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.642946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:35:28.813637Z","caller":"traceutil/trace.go:171","msg":"trace[936003642] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2178; }","duration":"113.83581ms","start":"2026-09-17T19:35:28.699777Z","end":"2026-09-17T19:35:28.813613Z","steps":["trace[936003642] 'count revisions from in-memory index tree' (duration: 113.472473ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:35:28.813659Z","caller":"traceutil/trace.go:171","msg":"trace[14355750] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"104.099117ms","start":"2026-09-17T19:35:28.709506Z","end":"2026-09-17T19:35:28.813605Z","steps":["trace[14355750] 'range keys from in-memory index tree' (duration: 103.595927ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:35:29.263028Z","caller":"traceutil/trace.go:171","msg":"trace[1219305430] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"221.500461ms","start":"2026-09-17T19:35:29.041399Z","end":"2026-09-17T19:35:29.2629Z","steps":["trace[1219305430] 'process raft request' (duration: 221.306467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:29.618122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.315835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:35:29.618281Z","caller":"traceutil/trace.go:171","msg":"trace[1364033153] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2263; }","duration":"496.203393ms","start":"2026-09-17T19:35:29.122053Z","end":"2026-09-17T19:35:29.618256Z","steps":["trace[1364033153] 'read index received' (duration: 140.791359ms)","trace[1364033153] 'applied index is now lower than readState.Index' (duration: 355.410334ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:35:29.618633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.57912ms","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-17T19:35:29.618686Z","caller":"traceutil/trace.go:171","msg":"trace[1016612856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2180; }","duration":"496.664582ms","start":"2026-09-17T19:35:29.122008Z","end":"2026-09-17T19:35:29.618673Z","steps":["trace[1016612856] 'agreement among raft nodes before linearized reading' (duration: 496.480799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:29.618732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:35:29.121996Z","time spent":"496.720723ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-17T19:35:29.618734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.745399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T19:35:29.618857Z","caller":"traceutil/trace.go:171","msg":"trace[219514624] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2180; }","duration":"435.934313ms","start":"2026-09-17T19:35:29.182895Z","end":"2026-09-17T19:35:29.618829Z","steps":["trace[219514624] 'agreement among raft nodes before linearized reading' (duration: 435.690979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:29.618908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:35:29.182874Z","time spent":"436.022595ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-09-17T19:35:29.618905Z","caller":"traceutil/trace.go:171","msg":"trace[866375752] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"497.06573ms","start":"2026-09-17T19:35:29.121751Z","end":"2026-09-17T19:35:29.618816Z","steps":["trace[866375752] 'process raft request' (duration: 303.955529ms)","trace[866375752] 'compare' (duration: 192.004109ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:35:29.618926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.133323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:35:29.619148Z","caller":"traceutil/trace.go:171","msg":"trace[1126565838] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2180; }","duration":"188.434478ms","start":"2026-09-17T19:35:29.430686Z","end":"2026-09-17T19:35:29.61912Z","steps":["trace[1126565838] 'agreement among raft nodes before linearized reading' (duration: 188.123502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:29.619215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:35:29.12172Z","time spent":"497.308965ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:35:30.098662Z","caller":"traceutil/trace.go:171","msg":"trace[1875805967] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"472.596764ms","start":"2026-09-17T19:35:29.626027Z","end":"2026-09-17T19:35:30.098624Z","steps":["trace[1875805967] 'process raft request' (duration: 443.607773ms)","trace[1875805967] 'compare' (duration: 28.499202ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:35:30.098869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:35:29.625994Z","time spent":"472.763128ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T19:35:30.099093Z","caller":"traceutil/trace.go:171","msg":"trace[368267784] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2265; }","duration":"365.378518ms","start":"2026-09-17T19:35:29.733684Z","end":"2026-09-17T19:35:30.099062Z","steps":["trace[368267784] 'read index received' (duration: 335.997489ms)","trace[368267784] 'applied index is now lower than readState.Index' (duration: 29.379549ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:35:30.099277Z","caller":"traceutil/trace.go:171","msg":"trace[399376524] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"429.033325ms","start":"2026-09-17T19:35:29.670227Z","end":"2026-09-17T19:35:30.09926Z","steps":["trace[399376524] 'process raft request' (duration: 428.623256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:30.099441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:35:29.670171Z","time spent":"429.139947ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:35:30.099825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.535317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-17T19:35:30.099874Z","caller":"traceutil/trace.go:171","msg":"trace[1477418188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2183; }","duration":"344.605218ms","start":"2026-09-17T19:35:29.755256Z","end":"2026-09-17T19:35:30.099861Z","steps":["trace[1477418188] 'agreement among raft nodes before linearized reading' (duration: 344.451495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:30.099917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:35:29.755245Z","time spent":"344.664149ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-17T19:35:30.1002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.50033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-17T19:35:30.100241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.173846ms","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-17T19:35:30.100271Z","caller":"traceutil/trace.go:171","msg":"trace[1545134335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2183; }","duration":"366.627803ms","start":"2026-09-17T19:35:29.733625Z","end":"2026-09-17T19:35:30.100253Z","steps":["trace[1545134335] 'agreement among raft nodes before linearized reading' (duration: 365.839287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:35:30.100338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:35:29.733609Z","time spent":"366.690784ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-17T19:35:30.100286Z","caller":"traceutil/trace.go:171","msg":"trace[171814134] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2183; }","duration":"279.249798ms","start":"2026-09-17T19:35:29.821021Z","end":"2026-09-17T19:35:30.10027Z","steps":["trace[171814134] 'agreement among raft nodes before linearized reading' (duration: 279.101005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:37:11.226721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.416205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-17T19:37:11.227035Z","caller":"traceutil/trace.go:171","msg":"trace[2016395396] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2820; }","duration":"116.784222ms","start":"2026-09-17T19:37:11.110228Z","end":"2026-09-17T19:37:11.227013Z","steps":["trace[2016395396] 'range keys from in-memory index tree' (duration: 116.244451ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:37:42.846397Z","caller":"traceutil/trace.go:171","msg":"trace[927969777] transaction","detail":"{read_only:false; response_revision:2989; number_of_response:1; }","duration":"247.562962ms","start":"2026-09-17T19:37:42.59881Z","end":"2026-09-17T19:37:42.846373Z","steps":["trace[927969777] 'process raft request' (duration: 247.357637ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:37:42.846816Z","caller":"traceutil/trace.go:171","msg":"trace[489260620] transaction","detail":"{read_only:false; response_revision:2990; number_of_response:1; }","duration":"234.461307ms","start":"2026-09-17T19:37:42.612325Z","end":"2026-09-17T19:37:42.846786Z","steps":["trace[489260620] 'process raft request' (duration: 233.987035ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:37:42.847037Z","caller":"traceutil/trace.go:171","msg":"trace[1950488211] transaction","detail":"{read_only:false; response_revision:2991; number_of_response:1; }","duration":"223.057211ms","start":"2026-09-17T19:37:42.623964Z","end":"2026-09-17T19:37:42.847022Z","steps":["trace[1950488211] 'process raft request' (duration: 222.61932ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:37:42.848036Z","caller":"traceutil/trace.go:171","msg":"trace[828783265] linearizableReadLoop","detail":"{readStateIndex:3107; appliedIndex:3102; }","duration":"151.715393ms","start":"2026-09-17T19:37:42.696302Z","end":"2026-09-17T19:37:42.848017Z","steps":["trace[828783265] 'read index received' (duration: 149.961499ms)","trace[828783265] 'applied index is now lower than readState.Index' (duration: 1.752564ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:37:42.848176Z","caller":"traceutil/trace.go:171","msg":"trace[399239774] transaction","detail":"{read_only:false; response_revision:2992; number_of_response:1; }","duration":"189.904545ms","start":"2026-09-17T19:37:42.658261Z","end":"2026-09-17T19:37:42.848166Z","steps":["trace[399239774] 'process raft request' (duration: 189.616077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:37:42.84859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.324116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-17T19:37:42.848971Z","caller":"traceutil/trace.go:171","msg":"trace[2139892690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2993; }","duration":"103.751127ms","start":"2026-09-17T19:37:42.745204Z","end":"2026-09-17T19:37:42.848956Z","steps":["trace[2139892690] 'agreement among raft nodes before linearized reading' (duration: 103.197633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:37:42.848612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.289187ms","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-17T19:37:42.849209Z","caller":"traceutil/trace.go:171","msg":"trace[1755095692] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2993; }","duration":"152.938244ms","start":"2026-09-17T19:37:42.696252Z","end":"2026-09-17T19:37:42.84919Z","steps":["trace[1755095692] 'agreement among raft nodes before linearized reading' (duration: 152.119633ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:37:42.848703Z","caller":"traceutil/trace.go:171","msg":"trace[1317971671] transaction","detail":"{read_only:false; response_revision:2993; number_of_response:1; }","duration":"175.900276ms","start":"2026-09-17T19:37:42.672787Z","end":"2026-09-17T19:37:42.848687Z","steps":["trace[1317971671] 'process raft request' (duration: 175.180998ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:02.325792Z","caller":"traceutil/trace.go:171","msg":"trace[2125286143] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"102.966329ms","start":"2026-09-17T19:42:02.222785Z","end":"2026-09-17T19:42:02.325751Z","steps":["trace[2125286143] 'process raft request' (duration: 100.101803ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:09.288085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2810} {"level":"info","ts":"2026-09-17T19:42:09.364108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2810,"took":"75.182964ms","hash":272084694,"current-db-size-bytes":50315264,"current-db-size":"50 MB","current-db-size-in-use-bytes":25247744,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-17T19:42:09.364173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":272084694,"revision":2810,"compact-revision":-1} {"level":"info","ts":"2026-09-17T19:42:20.804357Z","caller":"traceutil/trace.go:171","msg":"trace[1916977144] linearizableReadLoop","detail":"{readStateIndex:5419; appliedIndex:5418; }","duration":"245.973964ms","start":"2026-09-17T19:42:20.558358Z","end":"2026-09-17T19:42:20.804332Z","steps":["trace[1916977144] 'read index received' (duration: 209.346163ms)","trace[1916977144] 'applied index is now lower than readState.Index' (duration: 36.626541ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:42:20.804618Z","caller":"traceutil/trace.go:171","msg":"trace[1213704447] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"318.816357ms","start":"2026-09-17T19:42:20.485788Z","end":"2026-09-17T19:42:20.804604Z","steps":["trace[1213704447] 'process raft request' (duration: 281.967572ms)","trace[1213704447] 'compare' (duration: 36.332875ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:42:20.804709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:42:20.485765Z","time spent":"318.878559ms","remote":"127.0.0.1:57686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:42:20.804958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.596376ms","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-09-17T19:42:20.805465Z","caller":"traceutil/trace.go:171","msg":"trace[436507781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5219; }","duration":"247.109285ms","start":"2026-09-17T19:42:20.558319Z","end":"2026-09-17T19:42:20.805428Z","steps":["trace[436507781] 'agreement among raft nodes before linearized reading' (duration: 246.550015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:20.867353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.130343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-560233f3-ab87-4e00-839f-9bdf11eb9d2f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-17T19:42:20.867429Z","caller":"traceutil/trace.go:171","msg":"trace[574818266] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-560233f3-ab87-4e00-839f-9bdf11eb9d2f; range_end:; response_count:1; response_revision:5220; }","duration":"184.248426ms","start":"2026-09-17T19:42:20.683162Z","end":"2026-09-17T19:42:20.86741Z","steps":["trace[574818266] 'agreement among raft nodes before linearized reading' (duration: 183.485821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:21.343429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.269602ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:42:21.343551Z","caller":"traceutil/trace.go:171","msg":"trace[1121794688] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5223; }","duration":"155.423615ms","start":"2026-09-17T19:42:21.188107Z","end":"2026-09-17T19:42:21.343531Z","steps":["trace[1121794688] 'range keys from in-memory index tree' (duration: 155.255401ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:21.343764Z","caller":"traceutil/trace.go:171","msg":"trace[644938001] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"293.514255ms","start":"2026-09-17T19:42:21.050232Z","end":"2026-09-17T19:42:21.343747Z","steps":["trace[644938001] 'process raft request' (duration: 236.461518ms)","trace[644938001] 'compare' (duration: 56.885524ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:42:21.390937Z","caller":"traceutil/trace.go:171","msg":"trace[174973627] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"275.963034ms","start":"2026-09-17T19:42:21.114944Z","end":"2026-09-17T19:42:21.390907Z","steps":["trace[174973627] 'process raft request' (duration: 275.75261ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:34.294583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.637022ms","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":"info","ts":"2026-09-17T19:42:34.294664Z","caller":"traceutil/trace.go:171","msg":"trace[843636911] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5399; }","duration":"150.768285ms","start":"2026-09-17T19:42:34.143878Z","end":"2026-09-17T19:42:34.294647Z","steps":["trace[843636911] 'count revisions from in-memory index tree' (duration: 150.49044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:34.295297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.14745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-09-17T19:42:34.295346Z","caller":"traceutil/trace.go:171","msg":"trace[2073522814] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5399; }","duration":"140.281143ms","start":"2026-09-17T19:42:34.155053Z","end":"2026-09-17T19:42:34.295335Z","steps":["trace[2073522814] 'range keys from in-memory index tree' (duration: 139.337624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:34.611824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.895399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T19:42:34.611952Z","caller":"traceutil/trace.go:171","msg":"trace[120386109] linearizableReadLoop","detail":"{readStateIndex:5607; appliedIndex:5606; }","duration":"306.13235ms","start":"2026-09-17T19:42:34.305777Z","end":"2026-09-17T19:42:34.611909Z","steps":["trace[120386109] 'read index received' (duration: 200.954846ms)","trace[120386109] 'applied index is now lower than readState.Index' (duration: 105.175894ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T19:42:34.611971Z","caller":"traceutil/trace.go:171","msg":"trace[740518296] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"308.372582ms","start":"2026-09-17T19:42:34.303561Z","end":"2026-09-17T19:42:34.611933Z","steps":["trace[740518296] 'process raft request' (duration: 203.27173ms)","trace[740518296] 'compare' (duration: 104.617273ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T19:42:34.612228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.448186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-17T19:42:34.612286Z","caller":"traceutil/trace.go:171","msg":"trace[1415604203] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5401; }","duration":"306.543188ms","start":"2026-09-17T19:42:34.305725Z","end":"2026-09-17T19:42:34.612268Z","steps":["trace[1415604203] 'agreement among raft nodes before linearized reading' (duration: 306.322593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:34.612272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:42:34.303539Z","time spent":"308.675109ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T19:42:34.612341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T19:42:34.305706Z","time spent":"306.619459ms","remote":"127.0.0.1:57570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-09-17T19:42:34.612407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.897208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-17T19:42:34.6123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.273034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-17T19:42:34.612462Z","caller":"traceutil/trace.go:171","msg":"trace[1323321866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5401; }","duration":"134.999459ms","start":"2026-09-17T19:42:34.477447Z","end":"2026-09-17T19:42:34.612446Z","steps":["trace[1323321866] 'agreement among raft nodes before linearized reading' (duration: 134.885078ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:42:34.612488Z","caller":"traceutil/trace.go:171","msg":"trace[2060282679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5401; }","duration":"258.474596ms","start":"2026-09-17T19:42:34.353977Z","end":"2026-09-17T19:42:34.612451Z","steps":["trace[2060282679] 'agreement among raft nodes before linearized reading' (duration: 258.196392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:34.612648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.773623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T19:42:34.612704Z","caller":"traceutil/trace.go:171","msg":"trace[936878968] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:5401; }","duration":"229.827164ms","start":"2026-09-17T19:42:34.382858Z","end":"2026-09-17T19:42:34.612686Z","steps":["trace[936878968] 'agreement among raft nodes before linearized reading' (duration: 229.743922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:34.612736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.227707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-09-17T19:42:34.612768Z","caller":"traceutil/trace.go:171","msg":"trace[988881035] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5401; }","duration":"252.297618ms","start":"2026-09-17T19:42:34.36046Z","end":"2026-09-17T19:42:34.612758Z","steps":["trace[988881035] 'agreement among raft nodes before linearized reading' (duration: 252.181226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T19:42:34.613014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.420045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-17T19:42:34.613095Z","caller":"traceutil/trace.go:171","msg":"trace[73843979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5401; }","duration":"140.550038ms","start":"2026-09-17T19:42:34.47253Z","end":"2026-09-17T19:42:34.61308Z","steps":["trace[73843979] 'agreement among raft nodes before linearized reading' (duration: 140.392325ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T19:47:09.309791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4924} {"level":"info","ts":"2026-09-17T19:47:09.399321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4924,"took":"87.559007ms","hash":2125898905,"current-db-size-bytes":50315264,"current-db-size":"50 MB","current-db-size-in-use-bytes":20135936,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-17T19:47:09.399407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2125898905,"revision":4924,"compact-revision":2810}