{"level":"warn","ts":"2026-10-13T21:17:08.841694Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T21:17:08.841805Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.75: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.75:2380","--initial-cluster=instance=https://162.253.55.75:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.75:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.75:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-13T21:17:08.841901Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T21:17:08.841915Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.75:2380"]} {"level":"info","ts":"2026-10-13T21:17:08.841936Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-13T21:17:08.842827Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.75:2379"]} {"level":"info","ts":"2026-10-13T21:17:08.842949Z","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.75:2380"],"listen-peer-urls":["https://162.253.55.75:2380"],"advertise-client-urls":["https://162.253.55.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.75:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-13T21:17:08.854208Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.976591ms"} {"level":"info","ts":"2026-10-13T21:17:08.86964Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"871515da6373d131","cluster-id":"1ca87f0850671e76"} {"level":"info","ts":"2026-10-13T21:17:08.869757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T21:17:08.869787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became follower at term 0"} {"level":"info","ts":"2026-10-13T21:17:08.869809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 871515da6373d131 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T21:17:08.869826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became follower at term 1"} {"level":"info","ts":"2026-10-13T21:17:08.869867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 switched to configuration voters=(9733710197346783537)"} {"level":"warn","ts":"2026-10-13T21:17:08.880666Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T21:17:08.883048Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T21:17:08.885525Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-13T21:17:08.88783Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"871515da6373d131","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T21:17:08.888342Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"871515da6373d131","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T21:17:08.888398Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T21:17:08.888732Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T21:17:08.888781Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-13T21:17:08.889585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 switched to configuration voters=(9733710197346783537)"} {"level":"info","ts":"2026-10-13T21:17:08.889837Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1ca87f0850671e76","local-member-id":"871515da6373d131","added-peer-id":"871515da6373d131","added-peer-peer-urls":["https://162.253.55.75:2380"]} {"level":"info","ts":"2026-10-13T21:17:08.891778Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-13T21:17:08.89194Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.75:2380"} {"level":"info","ts":"2026-10-13T21:17:08.892008Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.75:2380"} {"level":"info","ts":"2026-10-13T21:17:08.892122Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T21:17:08.892144Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"871515da6373d131","initial-advertise-peer-urls":["https://162.253.55.75:2380"],"listen-peer-urls":["https://162.253.55.75:2380"],"advertise-client-urls":["https://162.253.55.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T21:17:08.970666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T21:17:08.970725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T21:17:08.970751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 received MsgPreVoteResp from 871515da6373d131 at term 1"} {"level":"info","ts":"2026-10-13T21:17:08.970764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became candidate at term 2"} {"level":"info","ts":"2026-10-13T21:17:08.970771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 received MsgVoteResp from 871515da6373d131 at term 2"} {"level":"info","ts":"2026-10-13T21:17:08.970783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"871515da6373d131 became leader at term 2"} {"level":"info","ts":"2026-10-13T21:17:08.970796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 871515da6373d131 elected leader 871515da6373d131 at term 2"} {"level":"info","ts":"2026-10-13T21:17:08.971676Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"871515da6373d131","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.75:2379]}","request-path":"/0/members/871515da6373d131/attributes","cluster-id":"1ca87f0850671e76","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T21:17:08.971683Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T21:17:08.971938Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T21:17:08.972Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T21:17:08.971771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T21:17:08.972017Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T21:17:08.973083Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1ca87f0850671e76","local-member-id":"871515da6373d131","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T21:17:08.973187Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T21:17:08.973234Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T21:17:08.974594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T21:17:08.975619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.75:2379"} {"level":"info","ts":"2026-10-13T21:17:12.657867Z","caller":"traceutil/trace.go:171","msg":"trace[2035596571] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"176.078159ms","start":"2026-10-13T21:17:12.481763Z","end":"2026-10-13T21:17:12.657841Z","steps":["trace[2035596571] 'process raft request' (duration: 176.000206ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:17:12.657976Z","caller":"traceutil/trace.go:171","msg":"trace[1578202415] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"176.951203ms","start":"2026-10-13T21:17:12.480964Z","end":"2026-10-13T21:17:12.657915Z","steps":["trace[1578202415] 'process raft request' (duration: 91.373249ms)","trace[1578202415] 'compare' (duration: 85.262092ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:17:14.942508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068230799s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:17:14.942648Z","caller":"traceutil/trace.go:171","msg":"trace[933358699] linearizableReadLoop","detail":"{readStateIndex:185; appliedIndex:184; }","duration":"1.494776523s","start":"2026-10-13T21:17:13.447853Z","end":"2026-10-13T21:17:14.94263Z","steps":["trace[933358699] 'read index received' (duration: 425.713729ms)","trace[933358699] 'applied index is now lower than readState.Index' (duration: 1.069061384s)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:17:14.942721Z","caller":"traceutil/trace.go:171","msg":"trace[1506222121] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"1.565127137s","start":"2026-10-13T21:17:13.377582Z","end":"2026-10-13T21:17:14.942709Z","steps":["trace[1506222121] 'process raft request' (duration: 496.041083ms)","trace[1506222121] 'compare' (duration: 1.067637584s)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:17:14.942789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:13.377552Z","time spent":"1.565205519s","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T21:17:14.942989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495104446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:17:14.943095Z","caller":"traceutil/trace.go:171","msg":"trace[302040913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:180; }","duration":"1.495242562s","start":"2026-10-13T21:17:13.447824Z","end":"2026-10-13T21:17:14.943067Z","steps":["trace[302040913] 'agreement among raft nodes before linearized reading' (duration: 1.494977651s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:14.94315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:13.447812Z","time spent":"1.495325526s","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T21:17:15.442732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15074006685923640991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-13T21:17:15.452202Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.128190072s","expected-duration":"1s"} {"level":"warn","ts":"2026-10-13T21:17:15.88723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999818904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-10-13T21:17:15.887308Z","caller":"traceutil/trace.go:171","msg":"trace[2088801764] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999912879s","start":"2026-10-13T21:17:13.887377Z","end":"2026-10-13T21:17:15.88729Z","steps":["trace[2088801764] 'agreement among raft nodes before linearized reading' (duration: 1.999816185s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:15.887353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:13.887332Z","time spent":"2.000011942s","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T21:17:16.048425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.910855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:17:16.048536Z","caller":"traceutil/trace.go:171","msg":"trace[1593806281] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"1.782096624s","start":"2026-10-13T21:17:14.266416Z","end":"2026-10-13T21:17:16.048513Z","steps":["trace[1593806281] 'process raft request' (duration: 1.186032813s)","trace[1593806281] 'compare' (duration: 595.518688ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:17:16.048605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:14.266392Z","time spent":"1.782183347s","remote":"127.0.0.1:54792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T21:17:16.063524Z","caller":"traceutil/trace.go:171","msg":"trace[1175635871] linearizableReadLoop","detail":"{readStateIndex:188; appliedIndex:185; }","duration":"1.120823589s","start":"2026-10-13T21:17:14.942677Z","end":"2026-10-13T21:17:16.063501Z","steps":["trace[1175635871] 'read index received' (duration: 509.78107ms)","trace[1175635871] 'applied index is now lower than readState.Index' (duration: 611.041539ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:17:16.063653Z","caller":"traceutil/trace.go:171","msg":"trace[656629985] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"1.116742882s","start":"2026-10-13T21:17:14.946874Z","end":"2026-10-13T21:17:16.063616Z","steps":["trace[656629985] 'process raft request' (duration: 1.116588006s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:16.063748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:14.946848Z","time spent":"1.116854896s","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T21:17:16.063873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951754591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:17:16.063939Z","caller":"traceutil/trace.go:171","msg":"trace[1597199019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:183; }","duration":"1.951875515s","start":"2026-10-13T21:17:14.112046Z","end":"2026-10-13T21:17:16.063921Z","steps":["trace[1597199019] 'agreement among raft nodes before linearized reading' (duration: 1.951707188s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:16.063971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:14.112028Z","time spent":"1.951934858s","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T21:17:16.064003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11719909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:17:16.064059Z","caller":"traceutil/trace.go:171","msg":"trace[913789654] range","detail":"{range_begin:/registry/clusterrolebindings/system:public-info-viewer; range_end:; response_count:0; response_revision:183; }","duration":"1.117298124s","start":"2026-10-13T21:17:14.946745Z","end":"2026-10-13T21:17:16.064043Z","steps":["trace[913789654] 'agreement among raft nodes before linearized reading' (duration: 1.117218691s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:16.064109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:14.946722Z","time spent":"1.117375587s","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" "} {"level":"warn","ts":"2026-10-13T21:17:16.064111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.959652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:17:16.064162Z","caller":"traceutil/trace.go:171","msg":"trace[1105208843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:183; }","duration":"176.040715ms","start":"2026-10-13T21:17:15.888107Z","end":"2026-10-13T21:17:16.064148Z","steps":["trace[1105208843] 'agreement among raft nodes before linearized reading' (duration: 175.958862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:16.064326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133612233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:17:16.064493Z","caller":"traceutil/trace.go:171","msg":"trace[1551694712] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"1.387736237s","start":"2026-10-13T21:17:14.676152Z","end":"2026-10-13T21:17:16.063888Z","steps":["trace[1551694712] 'process raft request' (duration: 1.387219685s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:16.064608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:14.67613Z","time spent":"1.388434765s","remote":"127.0.0.1:54792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T21:17:16.065178Z","caller":"traceutil/trace.go:171","msg":"trace[810541010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:183; }","duration":"1.13376552s","start":"2026-10-13T21:17:14.930657Z","end":"2026-10-13T21:17:16.064422Z","steps":["trace[810541010] 'agreement among raft nodes before linearized reading' (duration: 1.133311491s)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:16.065299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:14.930637Z","time spent":"1.134618544s","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T21:17:17.195218Z","caller":"traceutil/trace.go:171","msg":"trace[966784389] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"125.026382ms","start":"2026-10-13T21:17:17.070155Z","end":"2026-10-13T21:17:17.195181Z","steps":["trace[966784389] 'process raft request' (duration: 96.511232ms)","trace[966784389] 'compare' (duration: 28.323413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:17:18.160856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.561964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:17:18.161016Z","caller":"traceutil/trace.go:171","msg":"trace[237304905] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"166.965488ms","start":"2026-10-13T21:17:17.994027Z","end":"2026-10-13T21:17:18.160992Z","steps":["trace[237304905] 'read index received' (duration: 25.210505ms)","trace[237304905] 'applied index is now lower than readState.Index' (duration: 141.752823ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:17:18.161151Z","caller":"traceutil/trace.go:171","msg":"trace[180528874] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"173.286269ms","start":"2026-10-13T21:17:17.987849Z","end":"2026-10-13T21:17:18.161136Z","steps":["trace[180528874] 'process raft request' (duration: 31.365579ms)","trace[180528874] 'compare' (duration: 141.355326ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:17:18.161432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.55096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-10-13T21:17:18.161569Z","caller":"traceutil/trace.go:171","msg":"trace[127481885] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:267; }","duration":"166.719706ms","start":"2026-10-13T21:17:17.994826Z","end":"2026-10-13T21:17:18.161546Z","steps":["trace[127481885] 'agreement among raft nodes before linearized reading' (duration: 166.478506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:18.161846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.798641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-10-13T21:17:18.16217Z","caller":"traceutil/trace.go:171","msg":"trace[918989248] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:267; }","duration":"168.155736ms","start":"2026-10-13T21:17:17.993975Z","end":"2026-10-13T21:17:18.162131Z","steps":["trace[918989248] 'agreement among raft nodes before linearized reading' (duration: 167.76438ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:17:18.286514Z","caller":"traceutil/trace.go:171","msg":"trace[1200321524] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"118.429133ms","start":"2026-10-13T21:17:18.168044Z","end":"2026-10-13T21:17:18.286473Z","steps":["trace[1200321524] 'process raft request' (duration: 95.790014ms)","trace[1200321524] 'compare' (duration: 22.494123ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:17:18.286709Z","caller":"traceutil/trace.go:171","msg":"trace[1553478131] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"114.756931ms","start":"2026-10-13T21:17:18.171933Z","end":"2026-10-13T21:17:18.28669Z","steps":["trace[1553478131] 'process raft request' (duration: 114.48626ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:17:18.595021Z","caller":"traceutil/trace.go:171","msg":"trace[393753887] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"254.906287ms","start":"2026-10-13T21:17:18.340089Z","end":"2026-10-13T21:17:18.594995Z","steps":["trace[393753887] 'read index received' (duration: 184.169981ms)","trace[393753887] 'applied index is now lower than readState.Index' (duration: 70.735316ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:17:18.595122Z","caller":"traceutil/trace.go:171","msg":"trace[60591092] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"261.330383ms","start":"2026-10-13T21:17:18.333766Z","end":"2026-10-13T21:17:18.595097Z","steps":["trace[60591092] 'process raft request' (duration: 190.569706ms)","trace[60591092] 'compare' (duration: 70.536727ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:17:18.595208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.082883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-v155a6\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-10-13T21:17:18.595428Z","caller":"traceutil/trace.go:171","msg":"trace[1363054688] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-v155a6; range_end:; response_count:1; response_revision:274; }","duration":"254.340193ms","start":"2026-10-13T21:17:18.341069Z","end":"2026-10-13T21:17:18.595409Z","steps":["trace[1363054688] 'agreement among raft nodes before linearized reading' (duration: 254.068712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:18.595212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.104706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-13T21:17:18.595631Z","caller":"traceutil/trace.go:171","msg":"trace[424136112] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:274; }","duration":"255.569634ms","start":"2026-10-13T21:17:18.340049Z","end":"2026-10-13T21:17:18.595619Z","steps":["trace[424136112] 'agreement among raft nodes before linearized reading' (duration: 255.079294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:18.923189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.469452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:17:18.923483Z","caller":"traceutil/trace.go:171","msg":"trace[1050546176] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"320.834201ms","start":"2026-10-13T21:17:18.602614Z","end":"2026-10-13T21:17:18.923448Z","steps":["trace[1050546176] 'process raft request' (duration: 191.036685ms)","trace[1050546176] 'compare' (duration: 129.310156ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:17:18.923618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:18.602591Z","time spent":"320.964407ms","remote":"127.0.0.1:54730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T21:17:18.923839Z","caller":"traceutil/trace.go:171","msg":"trace[1682699981] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"314.972008ms","start":"2026-10-13T21:17:18.60885Z","end":"2026-10-13T21:17:18.923822Z","steps":["trace[1682699981] 'process raft request' (duration: 314.51734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:18.924005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.33171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-13T21:17:18.923833Z","caller":"traceutil/trace.go:171","msg":"trace[2141703281] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:281; }","duration":"286.11353ms","start":"2026-10-13T21:17:18.637659Z","end":"2026-10-13T21:17:18.923773Z","steps":["trace[2141703281] 'read index received' (duration: 155.893317ms)","trace[2141703281] 'applied index is now lower than readState.Index' (duration: 130.218923ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:17:18.924071Z","caller":"traceutil/trace.go:171","msg":"trace[946124075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:277; }","duration":"286.420824ms","start":"2026-10-13T21:17:18.637636Z","end":"2026-10-13T21:17:18.924057Z","steps":["trace[946124075] 'agreement among raft nodes before linearized reading' (duration: 286.234985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:18.92418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:18.608834Z","time spent":"315.086143ms","remote":"127.0.0.1:54792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T21:17:18.924252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.849399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:17:18.924346Z","caller":"traceutil/trace.go:171","msg":"trace[36343513] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:277; }","duration":"285.974505ms","start":"2026-10-13T21:17:18.638346Z","end":"2026-10-13T21:17:18.92432Z","steps":["trace[36343513] 'agreement among raft nodes before linearized reading' (duration: 285.822469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:27.406592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.770887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.75\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-13T21:17:27.406704Z","caller":"traceutil/trace.go:171","msg":"trace[347139624] range","detail":"{range_begin:/registry/masterleases/162.253.55.75; range_end:; response_count:1; response_revision:413; }","duration":"116.938614ms","start":"2026-10-13T21:17:27.289742Z","end":"2026-10-13T21:17:27.40668Z","steps":["trace[347139624] 'range keys from in-memory index tree' (duration: 116.621561ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:17:30.206886Z","caller":"traceutil/trace.go:171","msg":"trace[2141841294] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"192.080769ms","start":"2026-10-13T21:17:30.014782Z","end":"2026-10-13T21:17:30.206863Z","steps":["trace[2141841294] 'process raft request' (duration: 191.940813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:30.81842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.3604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:17:30.818554Z","caller":"traceutil/trace.go:171","msg":"trace[644774246] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"416.997105ms","start":"2026-10-13T21:17:30.401535Z","end":"2026-10-13T21:17:30.818532Z","steps":["trace[644774246] 'read index received' (duration: 38.436236ms)","trace[644774246] 'applied index is now lower than readState.Index' (duration: 378.558839ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:17:30.818637Z","caller":"traceutil/trace.go:171","msg":"trace[1954519178] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"455.068876ms","start":"2026-10-13T21:17:30.363536Z","end":"2026-10-13T21:17:30.818605Z","steps":["trace[1954519178] 'process raft request' (duration: 76.403643ms)","trace[1954519178] 'compare' (duration: 378.12496ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:17:30.818725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.183843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-13T21:17:30.81879Z","caller":"traceutil/trace.go:171","msg":"trace[43839289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"417.308768ms","start":"2026-10-13T21:17:30.401463Z","end":"2026-10-13T21:17:30.818771Z","steps":["trace[43839289] 'agreement among raft nodes before linearized reading' (duration: 417.172253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:17:30.818835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:30.401436Z","time spent":"417.387542ms","remote":"127.0.0.1:54876","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-10-13T21:17:30.818944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:17:30.363476Z","time spent":"455.250044ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:17:32.945751Z","caller":"traceutil/trace.go:171","msg":"trace[1224050641] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"118.737862ms","start":"2026-10-13T21:17:32.826982Z","end":"2026-10-13T21:17:32.94572Z","steps":["trace[1224050641] 'process raft request' (duration: 118.535273ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:17:35.716161Z","caller":"traceutil/trace.go:171","msg":"trace[2136153391] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"119.042449ms","start":"2026-10-13T21:17:35.597095Z","end":"2026-10-13T21:17:35.716137Z","steps":["trace[2136153391] 'process raft request' (duration: 118.934334ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:17:37.477587Z","caller":"traceutil/trace.go:171","msg":"trace[478722859] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"141.10974ms","start":"2026-10-13T21:17:37.336448Z","end":"2026-10-13T21:17:37.477558Z","steps":["trace[478722859] 'process raft request' (duration: 140.919832ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:17:41.337616Z","caller":"traceutil/trace.go:171","msg":"trace[884480133] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"166.989017ms","start":"2026-10-13T21:17:41.170605Z","end":"2026-10-13T21:17:41.337594Z","steps":["trace[884480133] 'process raft request' (duration: 166.82206ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:03.80751Z","caller":"traceutil/trace.go:171","msg":"trace[384221944] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"131.048889ms","start":"2026-10-13T21:18:03.676445Z","end":"2026-10-13T21:18:03.807494Z","steps":["trace[384221944] 'process raft request' (duration: 112.063056ms)","trace[384221944] 'compare' (duration: 17.923086ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:18:03.807618Z","caller":"traceutil/trace.go:171","msg":"trace[1324521832] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"124.611747ms","start":"2026-10-13T21:18:03.683002Z","end":"2026-10-13T21:18:03.807614Z","steps":["trace[1324521832] 'process raft request' (duration: 123.555679ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:04.63393Z","caller":"traceutil/trace.go:171","msg":"trace[1389583320] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"764.389911ms","start":"2026-10-13T21:18:03.869502Z","end":"2026-10-13T21:18:04.633891Z","steps":["trace[1389583320] 'process raft request' (duration: 751.921334ms)","trace[1389583320] 'compare' (duration: 12.242136ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:18:04.634407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:03.869479Z","time spent":"764.558459ms","remote":"127.0.0.1:54668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:18:04.635396Z","caller":"traceutil/trace.go:171","msg":"trace[775305801] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"760.83655ms","start":"2026-10-13T21:18:03.874516Z","end":"2026-10-13T21:18:04.635353Z","steps":["trace[775305801] 'process raft request' (duration: 759.999301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:04.635583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:03.874507Z","time spent":"760.977447ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:18:04.63635Z","caller":"traceutil/trace.go:171","msg":"trace[1603011676] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"740.672963ms","start":"2026-10-13T21:18:03.895228Z","end":"2026-10-13T21:18:04.635901Z","steps":["trace[1603011676] 'process raft request' (duration: 740.157999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:04.636597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:03.895206Z","time spent":"741.263719ms","remote":"127.0.0.1:54668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T21:18:11.316168Z","caller":"traceutil/trace.go:171","msg":"trace[241497684] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"128.516204ms","start":"2026-10-13T21:18:11.187613Z","end":"2026-10-13T21:18:11.316129Z","steps":["trace[241497684] 'read index received' (duration: 128.145017ms)","trace[241497684] 'applied index is now lower than readState.Index' (duration: 369.827µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:18:11.316452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.810207ms","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":"info","ts":"2026-10-13T21:18:11.316516Z","caller":"traceutil/trace.go:171","msg":"trace[468676117] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:682; }","duration":"128.905682ms","start":"2026-10-13T21:18:11.187592Z","end":"2026-10-13T21:18:11.316498Z","steps":["trace[468676117] 'agreement among raft nodes before linearized reading' (duration: 128.731184ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:11.316365Z","caller":"traceutil/trace.go:171","msg":"trace[1841512397] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"140.126287ms","start":"2026-10-13T21:18:11.176163Z","end":"2026-10-13T21:18:11.316289Z","steps":["trace[1841512397] 'process raft request' (duration: 139.716169ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:11.469869Z","caller":"traceutil/trace.go:171","msg":"trace[904830025] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"147.213393ms","start":"2026-10-13T21:18:11.322625Z","end":"2026-10-13T21:18:11.469838Z","steps":["trace[904830025] 'process raft request' (duration: 59.358797ms)","trace[904830025] 'compare' (duration: 87.674967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:18:12.099773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.641239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:18:12.100061Z","caller":"traceutil/trace.go:171","msg":"trace[379722747] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"451.030263ms","start":"2026-10-13T21:18:11.648983Z","end":"2026-10-13T21:18:12.100013Z","steps":["trace[379722747] 'read index received' (duration: 221.935814ms)","trace[379722747] 'applied index is now lower than readState.Index' (duration: 229.092929ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:18:12.100235Z","caller":"traceutil/trace.go:171","msg":"trace[1961023905] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"543.827165ms","start":"2026-10-13T21:18:11.556369Z","end":"2026-10-13T21:18:12.100197Z","steps":["trace[1961023905] 'process raft request' (duration: 314.651403ms)","trace[1961023905] 'compare' (duration: 228.516822ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:18:12.10036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:11.556334Z","time spent":"543.987833ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T21:18:12.100486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.526036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-13T21:18:12.100558Z","caller":"traceutil/trace.go:171","msg":"trace[1802223594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"451.657141ms","start":"2026-10-13T21:18:11.648882Z","end":"2026-10-13T21:18:12.100539Z","steps":["trace[1802223594] 'agreement among raft nodes before linearized reading' (duration: 451.434781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:12.100606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:11.64885Z","time spent":"451.745965ms","remote":"127.0.0.1:54876","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-10-13T21:18:12.100769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.797705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:18:12.101005Z","caller":"traceutil/trace.go:171","msg":"trace[933600556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"357.194692ms","start":"2026-10-13T21:18:11.743688Z","end":"2026-10-13T21:18:12.100883Z","steps":["trace[933600556] 'agreement among raft nodes before linearized reading' (duration: 356.682279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:12.101113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:11.743656Z","time spent":"357.441925ms","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T21:18:12.430597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.035545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:18:12.430851Z","caller":"traceutil/trace.go:171","msg":"trace[504295414] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"291.982178ms","start":"2026-10-13T21:18:12.138844Z","end":"2026-10-13T21:18:12.430827Z","steps":["trace[504295414] 'process raft request' (duration: 291.899354ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:12.430957Z","caller":"traceutil/trace.go:171","msg":"trace[206125865] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"325.3032ms","start":"2026-10-13T21:18:12.105632Z","end":"2026-10-13T21:18:12.430935Z","steps":["trace[206125865] 'process raft request' (duration: 220.751521ms)","trace[206125865] 'compare' (duration: 103.774634ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:18:12.431077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:12.105588Z","time spent":"325.442436ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:18:12.925038Z","caller":"traceutil/trace.go:171","msg":"trace[1591464336] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"257.094704ms","start":"2026-10-13T21:18:12.667874Z","end":"2026-10-13T21:18:12.924969Z","steps":["trace[1591464336] 'read index received' (duration: 203.834225ms)","trace[1591464336] 'applied index is now lower than readState.Index' (duration: 53.258449ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:18:12.925374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.474332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-13T21:18:12.925629Z","caller":"traceutil/trace.go:171","msg":"trace[930078505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"257.805116ms","start":"2026-10-13T21:18:12.667801Z","end":"2026-10-13T21:18:12.925606Z","steps":["trace[930078505] 'agreement among raft nodes before linearized reading' (duration: 257.312024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:14.473128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.533947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-13T21:18:14.473167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.32833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:18:14.473222Z","caller":"traceutil/trace.go:171","msg":"trace[1418311859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"998.623262ms","start":"2026-10-13T21:18:13.474575Z","end":"2026-10-13T21:18:14.473198Z","steps":["trace[1418311859] 'range keys from in-memory index tree' (duration: 998.387831ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:14.473245Z","caller":"traceutil/trace.go:171","msg":"trace[1836362280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"730.443945ms","start":"2026-10-13T21:18:13.742783Z","end":"2026-10-13T21:18:14.473227Z","steps":["trace[1836362280] 'range keys from in-memory index tree' (duration: 730.263597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:14.47329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:13.474519Z","time spent":"998.735588ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T21:18:14.473326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:13.742769Z","time spent":"730.54331ms","remote":"127.0.0.1:54616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T21:18:14.473122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.684731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-13T21:18:14.47347Z","caller":"traceutil/trace.go:171","msg":"trace[195725501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"367.045478ms","start":"2026-10-13T21:18:14.106342Z","end":"2026-10-13T21:18:14.473388Z","steps":["trace[195725501] 'range keys from in-memory index tree' (duration: 366.43383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:14.473547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:14.106229Z","time spent":"367.291709ms","remote":"127.0.0.1:54876","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-10-13T21:18:27.565025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.413818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T21:18:27.565144Z","caller":"traceutil/trace.go:171","msg":"trace[488294449] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"258.232778ms","start":"2026-10-13T21:18:27.306892Z","end":"2026-10-13T21:18:27.565125Z","steps":["trace[488294449] 'read index received' (duration: 90.61884ms)","trace[488294449] 'applied index is now lower than readState.Index' (duration: 167.612737ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:18:27.565259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.369884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-13T21:18:27.565315Z","caller":"traceutil/trace.go:171","msg":"trace[1087280479] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:827; }","duration":"258.449248ms","start":"2026-10-13T21:18:27.306856Z","end":"2026-10-13T21:18:27.565305Z","steps":["trace[1087280479] 'agreement among raft nodes before linearized reading' (duration: 258.315242ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:27.565437Z","caller":"traceutil/trace.go:171","msg":"trace[2135094940] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"259.902735ms","start":"2026-10-13T21:18:27.305503Z","end":"2026-10-13T21:18:27.565406Z","steps":["trace[2135094940] 'process raft request' (duration: 91.991225ms)","trace[2135094940] 'compare' (duration: 167.24412ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:18:27.56617Z","caller":"traceutil/trace.go:171","msg":"trace[2023660633] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"258.863418ms","start":"2026-10-13T21:18:27.307287Z","end":"2026-10-13T21:18:27.56615Z","steps":["trace[2023660633] 'process raft request' (duration: 258.814005ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:27.56764Z","caller":"traceutil/trace.go:171","msg":"trace[1641911183] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"260.136238ms","start":"2026-10-13T21:18:27.307167Z","end":"2026-10-13T21:18:27.567282Z","steps":["trace[1641911183] 'process raft request' (duration: 258.819226ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:30.282027Z","caller":"traceutil/trace.go:171","msg":"trace[1035516136] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"117.967552ms","start":"2026-10-13T21:18:30.164023Z","end":"2026-10-13T21:18:30.281991Z","steps":["trace[1035516136] 'read index received' (duration: 117.785864ms)","trace[1035516136] 'applied index is now lower than readState.Index' (duration: 180.198µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:18:30.282223Z","caller":"traceutil/trace.go:171","msg":"trace[1670601177] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"228.659255ms","start":"2026-10-13T21:18:30.053533Z","end":"2026-10-13T21:18:30.282192Z","steps":["trace[1670601177] 'process raft request' (duration: 228.266527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:30.282601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.569583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T21:18:30.282672Z","caller":"traceutil/trace.go:171","msg":"trace[1935448532] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:839; }","duration":"108.700169ms","start":"2026-10-13T21:18:30.173952Z","end":"2026-10-13T21:18:30.282652Z","steps":["trace[1935448532] 'agreement among raft nodes before linearized reading' (duration: 108.340812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:30.282941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.865775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-13T21:18:30.28305Z","caller":"traceutil/trace.go:171","msg":"trace[287818511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:839; }","duration":"119.047153ms","start":"2026-10-13T21:18:30.163977Z","end":"2026-10-13T21:18:30.283024Z","steps":["trace[287818511] 'agreement among raft nodes before linearized reading' (duration: 118.598742ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:30.538531Z","caller":"traceutil/trace.go:171","msg":"trace[538464619] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"176.178339ms","start":"2026-10-13T21:18:30.361936Z","end":"2026-10-13T21:18:30.538114Z","steps":["trace[538464619] 'process raft request' (duration: 168.189375ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:31.106952Z","caller":"traceutil/trace.go:171","msg":"trace[759677751] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"416.695673ms","start":"2026-10-13T21:18:30.69021Z","end":"2026-10-13T21:18:31.106905Z","steps":["trace[759677751] 'read index received' (duration: 416.331455ms)","trace[759677751] 'applied index is now lower than readState.Index' (duration: 360.747µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:18:31.10705Z","caller":"traceutil/trace.go:171","msg":"trace[554440676] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"430.683058ms","start":"2026-10-13T21:18:30.676315Z","end":"2026-10-13T21:18:31.106998Z","steps":["trace[554440676] 'process raft request' (duration: 430.31314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:31.107212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:30.676273Z","time spent":"430.853216ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T21:18:31.107252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.996928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-13T21:18:31.107304Z","caller":"traceutil/trace.go:171","msg":"trace[1608758993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:846; }","duration":"417.124383ms","start":"2026-10-13T21:18:30.690164Z","end":"2026-10-13T21:18:31.107289Z","steps":["trace[1608758993] 'agreement among raft nodes before linearized reading' (duration: 416.84975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:31.107352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:30.690149Z","time spent":"417.194286ms","remote":"127.0.0.1:54876","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-10-13T21:18:31.107725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.499761ms","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-10-13T21:18:31.107937Z","caller":"traceutil/trace.go:171","msg":"trace[1487002996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:846; }","duration":"417.617836ms","start":"2026-10-13T21:18:30.690176Z","end":"2026-10-13T21:18:31.107793Z","steps":["trace[1487002996] 'agreement among raft nodes before linearized reading' (duration: 416.934485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:31.108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:30.690161Z","time spent":"417.823795ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T21:18:31.602648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.390904ms","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-10-13T21:18:31.602826Z","caller":"traceutil/trace.go:171","msg":"trace[375734719] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:846; }","duration":"199.620474ms","start":"2026-10-13T21:18:31.403178Z","end":"2026-10-13T21:18:31.602798Z","steps":["trace[375734719] 'range keys from in-memory index tree' (duration: 199.366752ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:31.788117Z","caller":"traceutil/trace.go:171","msg":"trace[192451274] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"675.398077ms","start":"2026-10-13T21:18:31.112689Z","end":"2026-10-13T21:18:31.788087Z","steps":["trace[192451274] 'process raft request' (duration: 675.220339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:31.788382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:31.112667Z","time spent":"675.598446ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:18:31.8085Z","caller":"traceutil/trace.go:171","msg":"trace[1105768128] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"695.348683ms","start":"2026-10-13T21:18:31.113107Z","end":"2026-10-13T21:18:31.808456Z","steps":["trace[1105768128] 'process raft request' (duration: 694.420069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:31.808763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:31.113067Z","time spent":"695.545462ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:18:31.808769Z","caller":"traceutil/trace.go:171","msg":"trace[964146311] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"695.354783ms","start":"2026-10-13T21:18:31.113384Z","end":"2026-10-13T21:18:31.808739Z","steps":["trace[964146311] 'process raft request' (duration: 694.968674ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:31.809135Z","caller":"traceutil/trace.go:171","msg":"trace[1491160698] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"372.216801ms","start":"2026-10-13T21:18:31.436863Z","end":"2026-10-13T21:18:31.80908Z","steps":["trace[1491160698] 'process raft request' (duration: 371.811923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:18:31.80939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:31.436818Z","time spent":"372.41423ms","remote":"127.0.0.1:54844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T21:18:31.809089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:18:31.113367Z","time spent":"695.600004ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T21:18:39.078365Z","caller":"traceutil/trace.go:171","msg":"trace[176816135] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"140.089923ms","start":"2026-10-13T21:18:38.938243Z","end":"2026-10-13T21:18:39.078333Z","steps":["trace[176816135] 'process raft request' (duration: 139.81804ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:39.078867Z","caller":"traceutil/trace.go:171","msg":"trace[1038668403] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"140.224559ms","start":"2026-10-13T21:18:38.938559Z","end":"2026-10-13T21:18:39.078784Z","steps":["trace[1038668403] 'process raft request' (duration: 139.691384ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:18:39.19705Z","caller":"traceutil/trace.go:171","msg":"trace[243605429] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"108.17464ms","start":"2026-10-13T21:18:39.088836Z","end":"2026-10-13T21:18:39.197011Z","steps":["trace[243605429] 'process raft request' (duration: 88.814238ms)","trace[243605429] 'compare' (duration: 18.498542ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:18:56.76136Z","caller":"traceutil/trace.go:171","msg":"trace[919837678] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"129.942934ms","start":"2026-10-13T21:18:56.631372Z","end":"2026-10-13T21:18:56.761315Z","steps":["trace[919837678] 'process raft request' (duration: 126.359963ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:05.185776Z","caller":"traceutil/trace.go:171","msg":"trace[195413554] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"165.37449ms","start":"2026-10-13T21:19:05.020374Z","end":"2026-10-13T21:19:05.185749Z","steps":["trace[195413554] 'process raft request' (duration: 165.17392ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:05.499148Z","caller":"traceutil/trace.go:171","msg":"trace[404417644] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"296.267687ms","start":"2026-10-13T21:19:05.202856Z","end":"2026-10-13T21:19:05.499123Z","steps":["trace[404417644] 'process raft request' (duration: 295.836077ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:05.667167Z","caller":"traceutil/trace.go:171","msg":"trace[28215678] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"136.204373ms","start":"2026-10-13T21:19:05.530896Z","end":"2026-10-13T21:19:05.667101Z","steps":["trace[28215678] 'read index received' (duration: 109.159268ms)","trace[28215678] 'applied index is now lower than readState.Index' (duration: 27.043434ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:05.667251Z","caller":"traceutil/trace.go:171","msg":"trace[1427718495] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"164.057746ms","start":"2026-10-13T21:19:05.503105Z","end":"2026-10-13T21:19:05.667162Z","steps":["trace[1427718495] 'process raft request' (duration: 136.940638ms)","trace[1427718495] 'compare' (duration: 26.826635ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:19:05.667475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.544209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"info","ts":"2026-10-13T21:19:05.667546Z","caller":"traceutil/trace.go:171","msg":"trace[130642858] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1090; }","duration":"136.702097ms","start":"2026-10-13T21:19:05.530824Z","end":"2026-10-13T21:19:05.667526Z","steps":["trace[130642858] 'agreement among raft nodes before linearized reading' (duration: 136.458154ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:07.000506Z","caller":"traceutil/trace.go:171","msg":"trace[516311688] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"173.896702ms","start":"2026-10-13T21:19:06.826582Z","end":"2026-10-13T21:19:07.000479Z","steps":["trace[516311688] 'process raft request' (duration: 173.706392ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:26.377653Z","caller":"traceutil/trace.go:171","msg":"trace[1167845027] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"110.254165ms","start":"2026-10-13T21:19:26.267367Z","end":"2026-10-13T21:19:26.377621Z","steps":["trace[1167845027] 'process raft request' (duration: 110.095306ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:37.398246Z","caller":"traceutil/trace.go:171","msg":"trace[627069533] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"101.293934ms","start":"2026-10-13T21:19:37.296917Z","end":"2026-10-13T21:19:37.398211Z","steps":["trace[627069533] 'read index received' (duration: 101.057114ms)","trace[627069533] 'applied index is now lower than readState.Index' (duration: 235.11µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:19:37.398358Z","caller":"traceutil/trace.go:171","msg":"trace[882486104] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"174.963841ms","start":"2026-10-13T21:19:37.223351Z","end":"2026-10-13T21:19:37.398315Z","steps":["trace[882486104] 'process raft request' (duration: 174.659272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:19:37.398549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.569723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.75\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-13T21:19:37.398603Z","caller":"traceutil/trace.go:171","msg":"trace[474867117] range","detail":"{range_begin:/registry/masterleases/162.253.55.75; range_end:; response_count:1; response_revision:1195; }","duration":"101.736633ms","start":"2026-10-13T21:19:37.296849Z","end":"2026-10-13T21:19:37.398586Z","steps":["trace[474867117] 'agreement among raft nodes before linearized reading' (duration: 101.483294ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:19:40.618092Z","caller":"traceutil/trace.go:171","msg":"trace[1552542226] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"144.1305ms","start":"2026-10-13T21:19:40.473922Z","end":"2026-10-13T21:19:40.618053Z","steps":["trace[1552542226] 'process raft request' (duration: 143.95061ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:20:35.892896Z","caller":"traceutil/trace.go:171","msg":"trace[464426950] linearizableReadLoop","detail":"{readStateIndex:1482; appliedIndex:1481; }","duration":"149.70907ms","start":"2026-10-13T21:20:35.74316Z","end":"2026-10-13T21:20:35.892869Z","steps":["trace[464426950] 'read index received' (duration: 149.481647ms)","trace[464426950] 'applied index is now lower than readState.Index' (duration: 226.223µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T21:20:35.893083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.912063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T21:20:35.893123Z","caller":"traceutil/trace.go:171","msg":"trace[672066380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1418; }","duration":"150.035045ms","start":"2026-10-13T21:20:35.743078Z","end":"2026-10-13T21:20:35.893113Z","steps":["trace[672066380] 'agreement among raft nodes before linearized reading' (duration: 149.895723ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:20:35.893431Z","caller":"traceutil/trace.go:171","msg":"trace[1296877377] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"212.563099ms","start":"2026-10-13T21:20:35.680824Z","end":"2026-10-13T21:20:35.893387Z","steps":["trace[1296877377] 'process raft request' (duration: 211.900099ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T21:20:44.740036Z","caller":"traceutil/trace.go:171","msg":"trace[889428760] linearizableReadLoop","detail":"{readStateIndex:1516; appliedIndex:1515; }","duration":"446.847357ms","start":"2026-10-13T21:20:44.293164Z","end":"2026-10-13T21:20:44.740011Z","steps":["trace[889428760] 'read index received' (duration: 446.682745ms)","trace[889428760] 'applied index is now lower than readState.Index' (duration: 163.152µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T21:20:44.740129Z","caller":"traceutil/trace.go:171","msg":"trace[643156809] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"491.505311ms","start":"2026-10-13T21:20:44.24859Z","end":"2026-10-13T21:20:44.740096Z","steps":["trace[643156809] 'process raft request' (duration: 491.266628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:20:44.74021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.028081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T21:20:44.740249Z","caller":"traceutil/trace.go:171","msg":"trace[1953830784] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1450; }","duration":"447.170443ms","start":"2026-10-13T21:20:44.293068Z","end":"2026-10-13T21:20:44.740239Z","steps":["trace[1953830784] 'agreement among raft nodes before linearized reading' (duration: 447.076471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T21:20:44.740286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:20:44.293038Z","time spent":"447.238743ms","remote":"127.0.0.1:54988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-13T21:20:44.740303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T21:20:44.248562Z","time spent":"491.644234ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T21:20:44.740559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.430262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-13T21:20:44.740698Z","caller":"traceutil/trace.go:171","msg":"trace[1977025698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1450; }","duration":"250.620815ms","start":"2026-10-13T21:20:44.490039Z","end":"2026-10-13T21:20:44.74066Z","steps":["trace[1977025698] 'agreement among raft nodes before linearized reading' (duration: 250.237909ms)"],"step_count":1}