{"level":"warn","ts":"2026-10-04T09:50:24.779774Z","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-04T09:50:24.779901Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-04T09:50:24.779984Z","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-04T09:50:24.779994Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-10-04T09:50:24.780022Z","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-04T09:50:24.780732Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-10-04T09:50:24.780808Z","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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-04T09:50:24.783924Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.894579ms"} {"level":"info","ts":"2026-10-04T09:50:24.788388Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-10-04T09:50:24.788456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T09:50:24.788477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-10-04T09:50:24.788491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T09:50:24.788503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-10-04T09:50:24.788546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-10-04T09:50:24.793994Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T09:50:24.795525Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T09:50:24.796879Z","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-04T09:50:24.798895Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T09:50:24.799199Z","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-04T09:50:24.799357Z","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-04T09:50:24.79939Z","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-04T09:50:24.802505Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T09:50:24.803155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-10-04T09:50:24.803414Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-10-04T09:50:24.805921Z","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-04T09:50:24.80647Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T09:50:24.806544Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T09:50:24.806158Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-10-04T09:50:24.806804Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-10-04T09:50:25.788908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T09:50:25.788979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T09:50:25.789022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-10-04T09:50:25.789044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-10-04T09:50:25.78906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-10-04T09:50:25.789098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-10-04T09:50:25.789117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-10-04T09:50:25.791972Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:50:25.792998Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T09:50:25.792995Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T09:50:25.793269Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T09:50:25.793668Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:50:25.793756Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T09:50:25.793779Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T09:50:25.793817Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:50:25.793863Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:50:25.795294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T09:50:25.796365Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"warn","ts":"2026-10-04T09:50:29.261248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.657564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-04T09:50:29.261535Z","caller":"traceutil/trace.go:171","msg":"trace[1617823316] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"513.010647ms","start":"2026-10-04T09:50:28.748486Z","end":"2026-10-04T09:50:29.261496Z","steps":["trace[1617823316] 'process raft request' (duration: 158.420339ms)","trace[1617823316] 'compare' (duration: 353.470048ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:50:29.261738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:50:28.748472Z","time spent":"513.152871ms","remote":"127.0.0.1:48920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":867,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T09:50:29.261805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:50:28.771606Z","time spent":"490.193316ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-04T09:50:29.603415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.008121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-10-04T09:50:29.603509Z","caller":"traceutil/trace.go:171","msg":"trace[2017993609] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"338.717024ms","start":"2026-10-04T09:50:29.264776Z","end":"2026-10-04T09:50:29.603493Z","steps":["trace[2017993609] 'read index received' (duration: 196.551369ms)","trace[2017993609] 'applied index is now lower than readState.Index' (duration: 142.164565ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:50:29.603711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.869657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-04T09:50:29.603789Z","caller":"traceutil/trace.go:171","msg":"trace[865910183] range","detail":"{range_begin:/registry/clusterroles/system:controller:ephemeral-volume-controller; range_end:; response_count:0; response_revision:114; }","duration":"339.037232ms","start":"2026-10-04T09:50:29.264735Z","end":"2026-10-04T09:50:29.603773Z","steps":["trace[865910183] 'agreement among raft nodes before linearized reading' (duration: 338.832287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:50:29.603722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.490503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-04T09:50:29.603903Z","caller":"traceutil/trace.go:171","msg":"trace[66404124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:114; }","duration":"151.712739ms","start":"2026-10-04T09:50:29.452177Z","end":"2026-10-04T09:50:29.60389Z","steps":["trace[66404124] 'agreement among raft nodes before linearized reading' (duration: 151.413541ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:50:29.604116Z","caller":"traceutil/trace.go:171","msg":"trace[1556343250] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"340.693035ms","start":"2026-10-04T09:50:29.263403Z","end":"2026-10-04T09:50:29.604096Z","steps":["trace[1556343250] 'process raft request' (duration: 197.933676ms)","trace[1556343250] 'compare' (duration: 141.874366ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:50:29.603865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:50:29.264723Z","time spent":"339.100244ms","remote":"127.0.0.1:48920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" "} {"level":"warn","ts":"2026-10-04T09:50:29.604288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:50:29.263374Z","time spent":"340.813849ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T09:51:03.995031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.229199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T09:51:03.995147Z","caller":"traceutil/trace.go:171","msg":"trace[1058007380] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:527; }","duration":"126.406864ms","start":"2026-10-04T09:51:03.868719Z","end":"2026-10-04T09:51:03.995126Z","steps":["trace[1058007380] 'range keys from in-memory index tree' (duration: 126.109695ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.56276Z","caller":"traceutil/trace.go:171","msg":"trace[479006425] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"163.096623ms","start":"2026-10-04T09:51:04.399639Z","end":"2026-10-04T09:51:04.562735Z","steps":["trace[479006425] 'process raft request' (duration: 162.97501ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.589422Z","caller":"traceutil/trace.go:171","msg":"trace[969537150] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"186.685725ms","start":"2026-10-04T09:51:04.40271Z","end":"2026-10-04T09:51:04.589396Z","steps":["trace[969537150] 'process raft request' (duration: 186.46824ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.589504Z","caller":"traceutil/trace.go:171","msg":"trace[1342415229] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"169.144517ms","start":"2026-10-04T09:51:04.420339Z","end":"2026-10-04T09:51:04.589483Z","steps":["trace[1342415229] 'process raft request' (duration: 168.929821ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.589694Z","caller":"traceutil/trace.go:171","msg":"trace[1155130473] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"166.980269ms","start":"2026-10-04T09:51:04.422699Z","end":"2026-10-04T09:51:04.589679Z","steps":["trace[1155130473] 'process raft request' (duration: 166.64581ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.589788Z","caller":"traceutil/trace.go:171","msg":"trace[607599523] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"126.334651ms","start":"2026-10-04T09:51:04.463422Z","end":"2026-10-04T09:51:04.589756Z","steps":["trace[607599523] 'process raft request' (duration: 126.253149ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.589868Z","caller":"traceutil/trace.go:171","msg":"trace[571866186] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"187.260641ms","start":"2026-10-04T09:51:04.4026Z","end":"2026-10-04T09:51:04.58986Z","steps":["trace[571866186] 'process raft request' (duration: 186.441269ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.589916Z","caller":"traceutil/trace.go:171","msg":"trace[588689559] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"168.859018ms","start":"2026-10-04T09:51:04.421047Z","end":"2026-10-04T09:51:04.589906Z","steps":["trace[588689559] 'process raft request' (duration: 168.262322ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.589726Z","caller":"traceutil/trace.go:171","msg":"trace[1390432128] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"171.807567ms","start":"2026-10-04T09:51:04.41791Z","end":"2026-10-04T09:51:04.589717Z","steps":["trace[1390432128] 'process raft request' (duration: 171.310844ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.590001Z","caller":"traceutil/trace.go:171","msg":"trace[33142314] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"167.197684ms","start":"2026-10-04T09:51:04.422797Z","end":"2026-10-04T09:51:04.589994Z","steps":["trace[33142314] 'process raft request' (duration: 166.65365ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.590039Z","caller":"traceutil/trace.go:171","msg":"trace[942179894] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"141.656981ms","start":"2026-10-04T09:51:04.448376Z","end":"2026-10-04T09:51:04.590033Z","steps":["trace[942179894] 'process raft request' (duration: 141.180759ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:04.590035Z","caller":"traceutil/trace.go:171","msg":"trace[1278684830] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"157.251618ms","start":"2026-10-04T09:51:04.432744Z","end":"2026-10-04T09:51:04.589995Z","steps":["trace[1278684830] 'process raft request' (duration: 156.756055ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:05.758388Z","caller":"traceutil/trace.go:171","msg":"trace[13607247] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"147.266253ms","start":"2026-10-04T09:51:05.611092Z","end":"2026-10-04T09:51:05.758358Z","steps":["trace[13607247] 'process raft request' (duration: 83.097902ms)","trace[13607247] 'compare' (duration: 64.015767ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:51:10.96717Z","caller":"traceutil/trace.go:171","msg":"trace[1882197198] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"207.793064ms","start":"2026-10-04T09:51:10.759349Z","end":"2026-10-04T09:51:10.967142Z","steps":["trace[1882197198] 'process raft request' (duration: 206.957772ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:11.962561Z","caller":"traceutil/trace.go:171","msg":"trace[643888284] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"116.348539ms","start":"2026-10-04T09:51:11.846148Z","end":"2026-10-04T09:51:11.962497Z","steps":["trace[643888284] 'read index received' (duration: 108.863696ms)","trace[643888284] 'applied index is now lower than readState.Index' (duration: 7.483293ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:51:11.96273Z","caller":"traceutil/trace.go:171","msg":"trace[1847236191] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"446.815383ms","start":"2026-10-04T09:51:11.51587Z","end":"2026-10-04T09:51:11.962686Z","steps":["trace[1847236191] 'process raft request' (duration: 440.07877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:51:11.962856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.503512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-04T09:51:11.962853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.682548ms","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-04T09:51:11.962908Z","caller":"traceutil/trace.go:171","msg":"trace[2042813321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"103.572914ms","start":"2026-10-04T09:51:11.859321Z","end":"2026-10-04T09:51:11.962894Z","steps":["trace[2042813321] 'agreement among raft nodes before linearized reading' (duration: 103.41049ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:11.96297Z","caller":"traceutil/trace.go:171","msg":"trace[1736962994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"116.827592ms","start":"2026-10-04T09:51:11.846095Z","end":"2026-10-04T09:51:11.962923Z","steps":["trace[1736962994] 'agreement among raft nodes before linearized reading' (duration: 116.580915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:51:11.963394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:51:11.515832Z","time spent":"447.056889ms","remote":"127.0.0.1:48618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T09:51:24.449357Z","caller":"traceutil/trace.go:171","msg":"trace[1284805421] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"172.969405ms","start":"2026-10-04T09:51:24.276018Z","end":"2026-10-04T09:51:24.448988Z","steps":["trace[1284805421] 'process raft request' (duration: 172.500233ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:24.453587Z","caller":"traceutil/trace.go:171","msg":"trace[348377212] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"176.619514ms","start":"2026-10-04T09:51:24.27695Z","end":"2026-10-04T09:51:24.453569Z","steps":["trace[348377212] 'process raft request' (duration: 176.44989ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:24.453596Z","caller":"traceutil/trace.go:171","msg":"trace[1344539344] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"174.410205ms","start":"2026-10-04T09:51:24.27917Z","end":"2026-10-04T09:51:24.45358Z","steps":["trace[1344539344] 'process raft request' (duration: 174.338193ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:51:27.965661Z","caller":"traceutil/trace.go:171","msg":"trace[1166250010] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"151.806934ms","start":"2026-10-04T09:51:27.81383Z","end":"2026-10-04T09:51:27.965637Z","steps":["trace[1166250010] 'process raft request' (duration: 151.626769ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:26.619037Z","caller":"traceutil/trace.go:171","msg":"trace[186716734] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"164.770615ms","start":"2026-10-04T09:52:26.454248Z","end":"2026-10-04T09:52:26.619019Z","steps":["trace[186716734] 'read index received' (duration: 164.637991ms)","trace[186716734] 'applied index is now lower than readState.Index' (duration: 131.834µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:52:26.619134Z","caller":"traceutil/trace.go:171","msg":"trace[1063664274] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"218.257788ms","start":"2026-10-04T09:52:26.400836Z","end":"2026-10-04T09:52:26.619094Z","steps":["trace[1063664274] 'process raft request' (duration: 218.060143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:26.61923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.9656ms","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-04T09:52:26.61929Z","caller":"traceutil/trace.go:171","msg":"trace[1574581021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1164; }","duration":"165.055693ms","start":"2026-10-04T09:52:26.454213Z","end":"2026-10-04T09:52:26.619247Z","steps":["trace[1574581021] 'agreement among raft nodes before linearized reading' (duration: 164.924279ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:26.619276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.68941ms","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-04T09:52:26.619334Z","caller":"traceutil/trace.go:171","msg":"trace[1247684414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1164; }","duration":"155.773053ms","start":"2026-10-04T09:52:26.463544Z","end":"2026-10-04T09:52:26.619317Z","steps":["trace[1247684414] 'agreement among raft nodes before linearized reading' (duration: 155.610698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:26.61945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.019116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-04T09:52:26.619525Z","caller":"traceutil/trace.go:171","msg":"trace[897607876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1164; }","duration":"147.14293ms","start":"2026-10-04T09:52:26.472364Z","end":"2026-10-04T09:52:26.619507Z","steps":["trace[897607876] 'agreement among raft nodes before linearized reading' (duration: 146.892463ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:26.733407Z","caller":"traceutil/trace.go:171","msg":"trace[1258793748] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"110.466759ms","start":"2026-10-04T09:52:26.622921Z","end":"2026-10-04T09:52:26.733388Z","steps":["trace[1258793748] 'process raft request' (duration: 30.567824ms)","trace[1258793748] 'compare' (duration: 79.677939ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:52:26.733901Z","caller":"traceutil/trace.go:171","msg":"trace[924234750] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"109.061582ms","start":"2026-10-04T09:52:26.624817Z","end":"2026-10-04T09:52:26.733879Z","steps":["trace[924234750] 'process raft request' (duration: 108.946649ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:26.733934Z","caller":"traceutil/trace.go:171","msg":"trace[730469310] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"109.498874ms","start":"2026-10-04T09:52:26.624429Z","end":"2026-10-04T09:52:26.733928Z","steps":["trace[730469310] 'process raft request' (duration: 108.908268ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:27.663683Z","caller":"traceutil/trace.go:171","msg":"trace[549024651] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"276.19745ms","start":"2026-10-04T09:52:27.387458Z","end":"2026-10-04T09:52:27.663656Z","steps":["trace[549024651] 'process raft request' (duration: 276.074056ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:27.663875Z","caller":"traceutil/trace.go:171","msg":"trace[1467865274] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"105.304162ms","start":"2026-10-04T09:52:27.558533Z","end":"2026-10-04T09:52:27.663837Z","steps":["trace[1467865274] 'read index received' (duration: 104.89578ms)","trace[1467865274] 'applied index is now lower than readState.Index' (duration: 403.271µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:52:27.664098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.523367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T09:52:27.664176Z","caller":"traceutil/trace.go:171","msg":"trace[315253688] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1170; }","duration":"105.679631ms","start":"2026-10-04T09:52:27.558478Z","end":"2026-10-04T09:52:27.664158Z","steps":["trace[315253688] 'agreement among raft nodes before linearized reading' (duration: 105.480176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:28.900398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.811299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T09:52:28.900535Z","caller":"traceutil/trace.go:171","msg":"trace[201815651] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"253.589201ms","start":"2026-10-04T09:52:28.646931Z","end":"2026-10-04T09:52:28.90052Z","steps":["trace[201815651] 'read index received' (duration: 23.577107ms)","trace[201815651] 'applied index is now lower than readState.Index' (duration: 230.011074ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:52:28.90058Z","caller":"traceutil/trace.go:171","msg":"trace[1701034198] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"272.733777ms","start":"2026-10-04T09:52:28.627806Z","end":"2026-10-04T09:52:28.90054Z","steps":["trace[1701034198] 'process raft request' (duration: 42.708742ms)","trace[1701034198] 'compare' (duration: 229.626184ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:52:28.900747Z","caller":"traceutil/trace.go:171","msg":"trace[1205303671] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"248.475083ms","start":"2026-10-04T09:52:28.652255Z","end":"2026-10-04T09:52:28.90073Z","steps":["trace[1205303671] 'process raft request' (duration: 248.234966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:28.900868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.351746ms","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-04T09:52:28.900933Z","caller":"traceutil/trace.go:171","msg":"trace[1770070376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1173; }","duration":"163.466219ms","start":"2026-10-04T09:52:28.73745Z","end":"2026-10-04T09:52:28.900916Z","steps":["trace[1770070376] 'agreement among raft nodes before linearized reading' (duration: 163.246523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:28.900965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.030693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T09:52:28.900997Z","caller":"traceutil/trace.go:171","msg":"trace[907896679] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1172; }","duration":"254.089845ms","start":"2026-10-04T09:52:28.646897Z","end":"2026-10-04T09:52:28.900987Z","steps":["trace[907896679] 'agreement among raft nodes before linearized reading' (duration: 253.684244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:28.901015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.375977ms","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-04T09:52:28.901087Z","caller":"traceutil/trace.go:171","msg":"trace[659135761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"163.445108ms","start":"2026-10-04T09:52:28.737599Z","end":"2026-10-04T09:52:28.901044Z","steps":["trace[659135761] 'agreement among raft nodes before linearized reading' (duration: 163.291994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:28.901139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.637423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-04T09:52:28.901187Z","caller":"traceutil/trace.go:171","msg":"trace[549664205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"163.714256ms","start":"2026-10-04T09:52:28.737462Z","end":"2026-10-04T09:52:28.901176Z","steps":["trace[549664205] 'agreement among raft nodes before linearized reading' (duration: 163.555011ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:29.220446Z","caller":"traceutil/trace.go:171","msg":"trace[198657258] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"316.013024ms","start":"2026-10-04T09:52:28.904407Z","end":"2026-10-04T09:52:29.22042Z","steps":["trace[198657258] 'process raft request' (duration: 315.880141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:29.220585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:28.904386Z","time spent":"316.125348ms","remote":"127.0.0.1:48808","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-04T09:52:29.249796Z","caller":"traceutil/trace.go:171","msg":"trace[2019764791] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"344.575305ms","start":"2026-10-04T09:52:28.905194Z","end":"2026-10-04T09:52:29.24977Z","steps":["trace[2019764791] 'process raft request' (duration: 344.319488ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:29.249906Z","caller":"traceutil/trace.go:171","msg":"trace[163855472] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1213; }","duration":"144.218681ms","start":"2026-10-04T09:52:29.105666Z","end":"2026-10-04T09:52:29.249885Z","steps":["trace[163855472] 'read index received' (duration: 114.698054ms)","trace[163855472] 'applied index is now lower than readState.Index' (duration: 29.519497ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:52:29.249929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:28.905173Z","time spent":"344.693508ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T09:52:29.250123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.436307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T09:52:29.250161Z","caller":"traceutil/trace.go:171","msg":"trace[2013787958] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"344.864992ms","start":"2026-10-04T09:52:28.905271Z","end":"2026-10-04T09:52:29.250135Z","steps":["trace[2013787958] 'process raft request' (duration: 344.379729ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:29.250262Z","caller":"traceutil/trace.go:171","msg":"trace[385345970] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"343.573078ms","start":"2026-10-04T09:52:28.906678Z","end":"2026-10-04T09:52:29.250251Z","steps":["trace[385345970] 'process raft request' (duration: 343.110766ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:29.250269Z","caller":"traceutil/trace.go:171","msg":"trace[618186191] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1179; }","duration":"144.590091ms","start":"2026-10-04T09:52:29.105627Z","end":"2026-10-04T09:52:29.250217Z","steps":["trace[618186191] 'agreement among raft nodes before linearized reading' (duration: 144.377045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:29.250315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:28.905259Z","time spent":"344.983046ms","remote":"127.0.0.1:48808","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-04T09:52:29.25038Z","caller":"traceutil/trace.go:171","msg":"trace[396927466] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"343.322331ms","start":"2026-10-04T09:52:28.907041Z","end":"2026-10-04T09:52:29.250363Z","steps":["trace[396927466] 'process raft request' (duration: 342.792827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:29.250322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:28.906665Z","time spent":"343.63376ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T09:52:29.250485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:28.907028Z","time spent":"343.401303ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T09:52:29.249978Z","caller":"traceutil/trace.go:171","msg":"trace[1388341584] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"344.665368ms","start":"2026-10-04T09:52:28.905293Z","end":"2026-10-04T09:52:29.249958Z","steps":["trace[1388341584] 'process raft request' (duration: 344.445542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:29.250808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:28.905278Z","time spent":"345.495879ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T09:52:29.406429Z","caller":"traceutil/trace.go:171","msg":"trace[2146086815] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"133.07201ms","start":"2026-10-04T09:52:29.273332Z","end":"2026-10-04T09:52:29.406404Z","steps":["trace[2146086815] 'process raft request' (duration: 132.963187ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:29.406594Z","caller":"traceutil/trace.go:171","msg":"trace[1104149274] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"133.135492ms","start":"2026-10-04T09:52:29.273431Z","end":"2026-10-04T09:52:29.406566Z","steps":["trace[1104149274] 'process raft request' (duration: 132.922866ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:29.406942Z","caller":"traceutil/trace.go:171","msg":"trace[21346898] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"147.306054ms","start":"2026-10-04T09:52:29.259615Z","end":"2026-10-04T09:52:29.406921Z","steps":["trace[21346898] 'process raft request' (duration: 66.137375ms)","trace[21346898] 'compare' (duration: 80.241354ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:52:39.700756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490026491888148122,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-04T09:52:39.847671Z","caller":"traceutil/trace.go:171","msg":"trace[302087413] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"647.554077ms","start":"2026-10-04T09:52:39.200092Z","end":"2026-10-04T09:52:39.847647Z","steps":["trace[302087413] 'process raft request' (duration: 647.407163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:39.847967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.200043Z","time spent":"647.809484ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T09:52:39.855342Z","caller":"traceutil/trace.go:171","msg":"trace[1295222424] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1291; }","duration":"655.186503ms","start":"2026-10-04T09:52:39.200132Z","end":"2026-10-04T09:52:39.855318Z","steps":["trace[1295222424] 'read index received' (duration: 647.370602ms)","trace[1295222424] 'applied index is now lower than readState.Index' (duration: 7.814581ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:52:39.855402Z","caller":"traceutil/trace.go:171","msg":"trace[174108217] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"544.103937ms","start":"2026-10-04T09:52:39.311272Z","end":"2026-10-04T09:52:39.855376Z","steps":["trace[174108217] 'process raft request' (duration: 543.911612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:39.85548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.328277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T09:52:39.855509Z","caller":"traceutil/trace.go:171","msg":"trace[50282243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"655.417019ms","start":"2026-10-04T09:52:39.200083Z","end":"2026-10-04T09:52:39.8555Z","steps":["trace[50282243] 'agreement among raft nodes before linearized reading' (duration: 655.351268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:39.855548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.200036Z","time spent":"655.505023ms","remote":"127.0.0.1:48540","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-04T09:52:39.855678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.311256Z","time spent":"544.242691ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T09:52:39.855699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.885585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T09:52:39.855845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.174996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-10-04T09:52:39.855881Z","caller":"traceutil/trace.go:171","msg":"trace[866738159] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1250; }","duration":"632.132692ms","start":"2026-10-04T09:52:39.22373Z","end":"2026-10-04T09:52:39.855862Z","steps":["trace[866738159] 'agreement among raft nodes before linearized reading' (duration: 631.920366ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:39.855915Z","caller":"traceutil/trace.go:171","msg":"trace[2112613043] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1250; }","duration":"436.285449ms","start":"2026-10-04T09:52:39.419611Z","end":"2026-10-04T09:52:39.855897Z","steps":["trace[2112613043] 'agreement among raft nodes before linearized reading' (duration: 436.081954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:39.855925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.127747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T09:52:39.855964Z","caller":"traceutil/trace.go:171","msg":"trace[177537923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"574.191808ms","start":"2026-10-04T09:52:39.28176Z","end":"2026-10-04T09:52:39.855952Z","steps":["trace[177537923] 'agreement among raft nodes before linearized reading' (duration: 574.090086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:39.855999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.281747Z","time spent":"574.245751ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T09:52:39.855996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.417823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T09:52:39.855961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.419589Z","time spent":"436.360131ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-10-04T09:52:39.856089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.953586ms","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-04T09:52:39.856118Z","caller":"traceutil/trace.go:171","msg":"trace[2089066586] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1250; }","duration":"145.591947ms","start":"2026-10-04T09:52:39.710503Z","end":"2026-10-04T09:52:39.856095Z","steps":["trace[2089066586] 'agreement among raft nodes before linearized reading' (duration: 145.379812ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:52:39.85613Z","caller":"traceutil/trace.go:171","msg":"trace[1632266708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"388.068209ms","start":"2026-10-04T09:52:39.468051Z","end":"2026-10-04T09:52:39.856119Z","steps":["trace[1632266708] 'agreement among raft nodes before linearized reading' (duration: 387.932705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:39.856191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.468036Z","time spent":"388.144181ms","remote":"127.0.0.1:48808","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-04T09:52:39.856098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.303206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-04T09:52:39.856196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.223718Z","time spent":"632.199814ms","remote":"127.0.0.1:47300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-10-04T09:52:39.856331Z","caller":"traceutil/trace.go:171","msg":"trace[696542261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"520.617814ms","start":"2026-10-04T09:52:39.335687Z","end":"2026-10-04T09:52:39.856305Z","steps":["trace[696542261] 'agreement among raft nodes before linearized reading' (duration: 520.153312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:39.856382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.335665Z","time spent":"520.705456ms","remote":"127.0.0.1:48808","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-10-04T09:52:39.856294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.33499ms","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-04T09:52:39.856531Z","caller":"traceutil/trace.go:171","msg":"trace[1652968933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"522.593797ms","start":"2026-10-04T09:52:39.333916Z","end":"2026-10-04T09:52:39.85651Z","steps":["trace[1652968933] 'agreement among raft nodes before linearized reading' (duration: 521.868648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:52:39.856667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:52:39.333898Z","time spent":"522.732972ms","remote":"127.0.0.1:48808","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\" "}