{"level":"warn","ts":"2026-10-13T12:34:59.598635Z","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-13T12:34:59.598782Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.189: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.189:2380","--initial-cluster=instance=https://162.253.55.189:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.189:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.189: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-13T12:34:59.598883Z","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-13T12:34:59.598925Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.189:2380"]} {"level":"info","ts":"2026-10-13T12:34:59.598979Z","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-13T12:34:59.600688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"]} {"level":"info","ts":"2026-10-13T12:34:59.600867Z","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.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.189: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-13T12:34:59.605615Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.473435ms"} {"level":"info","ts":"2026-10-13T12:34:59.611976Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f14c7d724df9ef94","cluster-id":"f7bde5cbb4d65447"} {"level":"info","ts":"2026-10-13T12:34:59.612044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T12:34:59.612062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 0"} {"level":"info","ts":"2026-10-13T12:34:59.612069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f14c7d724df9ef94 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T12:34:59.612077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 1"} {"level":"info","ts":"2026-10-13T12:34:59.612105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"} {"level":"warn","ts":"2026-10-13T12:34:59.617247Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T12:34:59.618893Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T12:34:59.621244Z","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-13T12:34:59.623624Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f14c7d724df9ef94","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T12:34:59.623753Z","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-13T12:34:59.623828Z","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-13T12:34:59.623839Z","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-13T12:34:59.623847Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f14c7d724df9ef94","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T12:34:59.627867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"} {"level":"info","ts":"2026-10-13T12:34:59.627984Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","added-peer-id":"f14c7d724df9ef94","added-peer-peer-urls":["https://162.253.55.189:2380"]} {"level":"info","ts":"2026-10-13T12:34:59.628554Z","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-13T12:34:59.62868Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.189:2380"} {"level":"info","ts":"2026-10-13T12:34:59.628745Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.189:2380"} {"level":"info","ts":"2026-10-13T12:34:59.629455Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f14c7d724df9ef94","initial-advertise-peer-urls":["https://162.253.55.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T12:34:59.629486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T12:35:00.312347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T12:35:00.312386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T12:35:00.312406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgPreVoteResp from f14c7d724df9ef94 at term 1"} {"level":"info","ts":"2026-10-13T12:35:00.312417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became candidate at term 2"} {"level":"info","ts":"2026-10-13T12:35:00.312428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgVoteResp from f14c7d724df9ef94 at term 2"} {"level":"info","ts":"2026-10-13T12:35:00.312436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became leader at term 2"} {"level":"info","ts":"2026-10-13T12:35:00.312444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f14c7d724df9ef94 elected leader f14c7d724df9ef94 at term 2"} {"level":"info","ts":"2026-10-13T12:35:00.313487Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:35:00.31437Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f14c7d724df9ef94","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.189:2379]}","request-path":"/0/members/f14c7d724df9ef94/attributes","cluster-id":"f7bde5cbb4d65447","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T12:35:00.314395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:35:00.314711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T12:35:00.315271Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:35:00.315391Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:35:00.315409Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T12:35:00.315484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T12:35:00.315496Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T12:35:00.316394Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.189:2379"} {"level":"info","ts":"2026-10-13T12:35:00.317916Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T12:35:05.36687Z","caller":"traceutil/trace.go:171","msg":"trace[1231747648] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"182.54553ms","start":"2026-10-13T12:35:05.184295Z","end":"2026-10-13T12:35:05.36684Z","steps":["trace[1231747648] 'process raft request' (duration: 182.395966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.811723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.136176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-13T12:35:05.81193Z","caller":"traceutil/trace.go:171","msg":"trace[296661126] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"599.198992ms","start":"2026-10-13T12:35:05.212703Z","end":"2026-10-13T12:35:05.811902Z","steps":["trace[296661126] 'process raft request' (duration: 279.141654ms)","trace[296661126] 'compare' (duration: 319.002103ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:35:05.811952Z","caller":"traceutil/trace.go:171","msg":"trace[1944651945] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:243; }","duration":"530.720625ms","start":"2026-10-13T12:35:05.281207Z","end":"2026-10-13T12:35:05.811927Z","steps":["trace[1944651945] 'read index received' (duration: 85.430244ms)","trace[1944651945] 'applied index is now lower than readState.Index' (duration: 445.289251ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:35:05.812028Z","caller":"traceutil/trace.go:171","msg":"trace[181178712] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"462.240836ms","start":"2026-10-13T12:35:05.349776Z","end":"2026-10-13T12:35:05.812017Z","steps":["trace[181178712] 'process raft request' (duration: 462.080012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.812062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.212677Z","time spent":"599.308014ms","remote":"127.0.0.1:33794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":219,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T12:35:05.812204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.987971ms","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-13T12:35:05.81226Z","caller":"traceutil/trace.go:171","msg":"trace[393806928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:240; }","duration":"531.083903ms","start":"2026-10-13T12:35:05.281161Z","end":"2026-10-13T12:35:05.812245Z","steps":["trace[393806928] 'agreement among raft nodes before linearized reading' (duration: 530.981381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.812307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.281142Z","time spent":"531.153674ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T12:35:05.812115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.34975Z","time spent":"462.315998ms","remote":"127.0.0.1:33666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T12:35:05.888553Z","caller":"traceutil/trace.go:171","msg":"trace[526883692] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"439.315301ms","start":"2026-10-13T12:35:05.449183Z","end":"2026-10-13T12:35:05.888498Z","steps":["trace[526883692] 'process raft request' (duration: 439.084406ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:05.888561Z","caller":"traceutil/trace.go:171","msg":"trace[1918292824] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"414.547513ms","start":"2026-10-13T12:35:05.473979Z","end":"2026-10-13T12:35:05.888527Z","steps":["trace[1918292824] 'process raft request' (duration: 414.3973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.888702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.44916Z","time spent":"439.475225ms","remote":"127.0.0.1:33772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T12:35:05.888739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.47396Z","time spent":"414.690367ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T12:35:05.888883Z","caller":"traceutil/trace.go:171","msg":"trace[195961545] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"414.563664ms","start":"2026-10-13T12:35:05.474301Z","end":"2026-10-13T12:35:05.888865Z","steps":["trace[195961545] 'process raft request' (duration: 414.128354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.888965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.474289Z","time spent":"414.639395ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T12:35:05.889225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.83874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"warn","ts":"2026-10-13T12:35:05.889234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.177185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-10-13T12:35:05.88927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.001637ms","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-13T12:35:05.889301Z","caller":"traceutil/trace.go:171","msg":"trace[100073815] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:243; }","duration":"540.277367ms","start":"2026-10-13T12:35:05.349004Z","end":"2026-10-13T12:35:05.889281Z","steps":["trace[100073815] 'agreement among raft nodes before linearized reading' (duration: 540.117393ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:05.889324Z","caller":"traceutil/trace.go:171","msg":"trace[621255610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:243; }","duration":"596.064999ms","start":"2026-10-13T12:35:05.293233Z","end":"2026-10-13T12:35:05.889298Z","steps":["trace[621255610] 'agreement among raft nodes before linearized reading' (duration: 596.000318ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:05.889288Z","caller":"traceutil/trace.go:171","msg":"trace[2061641231] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:243; }","duration":"510.956763ms","start":"2026-10-13T12:35:05.378314Z","end":"2026-10-13T12:35:05.88927Z","steps":["trace[2061641231] 'agreement among raft nodes before linearized reading' (duration: 510.80448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.889335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.786572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T12:35:05.889383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.293218Z","time spent":"596.155071ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-13T12:35:05.889383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.348986Z","time spent":"540.38329ms","remote":"127.0.0.1:34030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-13T12:35:05.889414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.378296Z","time spent":"511.103226ms","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":139,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-10-13T12:35:05.889431Z","caller":"traceutil/trace.go:171","msg":"trace[857661014] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:243; }","duration":"535.945406ms","start":"2026-10-13T12:35:05.353467Z","end":"2026-10-13T12:35:05.889412Z","steps":["trace[857661014] 'agreement among raft nodes before linearized reading' (duration: 535.777392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.889483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.353457Z","time spent":"536.012217ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-10-13T12:35:05.889608Z","caller":"traceutil/trace.go:171","msg":"trace[310489519] transaction","detail":"{read_only:false; number_of_response:0; response_revision:241; }","duration":"415.159248ms","start":"2026-10-13T12:35:05.474433Z","end":"2026-10-13T12:35:05.889592Z","steps":["trace[310489519] 'process raft request' (duration: 414.064772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.889679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.474407Z","time spent":"415.235639ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T12:35:05.889825Z","caller":"traceutil/trace.go:171","msg":"trace[1543002486] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"411.811369ms","start":"2026-10-13T12:35:05.477999Z","end":"2026-10-13T12:35:05.88981Z","steps":["trace[1543002486] 'process raft request' (duration: 410.606691ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:05.889842Z","caller":"traceutil/trace.go:171","msg":"trace[1743866481] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"411.42183ms","start":"2026-10-13T12:35:05.478407Z","end":"2026-10-13T12:35:05.889829Z","steps":["trace[1743866481] 'process raft request' (duration: 410.252803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:05.889885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.477981Z","time spent":"411.873731ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T12:35:05.889944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:05.478395Z","time spent":"411.491272ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T12:35:06.046068Z","caller":"traceutil/trace.go:171","msg":"trace[1905648423] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"152.930709ms","start":"2026-10-13T12:35:05.893108Z","end":"2026-10-13T12:35:06.046038Z","steps":["trace[1905648423] 'process raft request' (duration: 152.902318ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:06.04609Z","caller":"traceutil/trace.go:171","msg":"trace[123935135] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:252; }","duration":"157.2699ms","start":"2026-10-13T12:35:05.888794Z","end":"2026-10-13T12:35:06.046063Z","steps":["trace[123935135] 'read index received' (duration: 142.090316ms)","trace[123935135] 'applied index is now lower than readState.Index' (duration: 15.175824ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:35:06.046193Z","caller":"traceutil/trace.go:171","msg":"trace[1597980867] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"229.423083ms","start":"2026-10-13T12:35:05.816739Z","end":"2026-10-13T12:35:06.046162Z","steps":["trace[1597980867] 'process raft request' (duration: 229.180918ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:06.046248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.031811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:35:06.046287Z","caller":"traceutil/trace.go:171","msg":"trace[83583312] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:0; response_revision:247; }","duration":"231.121813ms","start":"2026-10-13T12:35:05.815153Z","end":"2026-10-13T12:35:06.046275Z","steps":["trace[83583312] 'agreement among raft nodes before linearized reading' (duration: 231.023871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:06.046498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.724297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-10-13T12:35:06.046533Z","caller":"traceutil/trace.go:171","msg":"trace[433407574] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:247; }","duration":"156.792049ms","start":"2026-10-13T12:35:05.889731Z","end":"2026-10-13T12:35:06.046523Z","steps":["trace[433407574] 'agreement among raft nodes before linearized reading' (duration: 156.727277ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:06.046506Z","caller":"traceutil/trace.go:171","msg":"trace[623241968] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"154.30336ms","start":"2026-10-13T12:35:05.891806Z","end":"2026-10-13T12:35:06.046109Z","steps":["trace[623241968] 'process raft request' (duration: 154.161827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:06.046711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.791745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-10-13T12:35:06.046744Z","caller":"traceutil/trace.go:171","msg":"trace[2043151169] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:247; }","duration":"152.857807ms","start":"2026-10-13T12:35:05.893878Z","end":"2026-10-13T12:35:06.046735Z","steps":["trace[2043151169] 'agreement among raft nodes before linearized reading' (duration: 152.804966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:06.046858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.103936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-10-13T12:35:06.046887Z","caller":"traceutil/trace.go:171","msg":"trace[947617111] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:247; }","duration":"154.215778ms","start":"2026-10-13T12:35:05.892664Z","end":"2026-10-13T12:35:06.046879Z","steps":["trace[947617111] 'agreement among raft nodes before linearized reading' (duration: 154.167977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:06.047009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.351892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-10-13T12:35:06.047037Z","caller":"traceutil/trace.go:171","msg":"trace[2117521821] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:247; }","duration":"154.397913ms","start":"2026-10-13T12:35:05.892632Z","end":"2026-10-13T12:35:06.047029Z","steps":["trace[2117521821] 'agreement among raft nodes before linearized reading' (duration: 154.352292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:06.047113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.688929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-10-13T12:35:06.047165Z","caller":"traceutil/trace.go:171","msg":"trace[121516335] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:247; }","duration":"154.764351ms","start":"2026-10-13T12:35:05.892385Z","end":"2026-10-13T12:35:06.047149Z","steps":["trace[121516335] 'agreement among raft nodes before linearized reading' (duration: 154.673229ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:06.047636Z","caller":"traceutil/trace.go:171","msg":"trace[905749753] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"232.004823ms","start":"2026-10-13T12:35:05.815601Z","end":"2026-10-13T12:35:06.047605Z","steps":["trace[905749753] 'process raft request' (duration: 215.298753ms)","trace[905749753] 'compare' (duration: 14.883878ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:35:42.432895Z","caller":"traceutil/trace.go:171","msg":"trace[932241145] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"127.268439ms","start":"2026-10-13T12:35:42.305604Z","end":"2026-10-13T12:35:42.432872Z","steps":["trace[932241145] 'read index received' (duration: 127.113756ms)","trace[932241145] 'applied index is now lower than readState.Index' (duration: 153.703µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:35:42.433029Z","caller":"traceutil/trace.go:171","msg":"trace[1678570360] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"131.298584ms","start":"2026-10-13T12:35:42.301681Z","end":"2026-10-13T12:35:42.432979Z","steps":["trace[1678570360] 'process raft request' (duration: 131.019157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:42.43309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.461584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-13T12:35:42.433125Z","caller":"traceutil/trace.go:171","msg":"trace[353989338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"127.528686ms","start":"2026-10-13T12:35:42.305585Z","end":"2026-10-13T12:35:42.433114Z","steps":["trace[353989338] 'agreement among raft nodes before linearized reading' (duration: 127.434724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:42.433288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.65924ms","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-13T12:35:42.433404Z","caller":"traceutil/trace.go:171","msg":"trace[1293437272] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:492; }","duration":"121.819313ms","start":"2026-10-13T12:35:42.31156Z","end":"2026-10-13T12:35:42.433379Z","steps":["trace[1293437272] 'agreement among raft nodes before linearized reading' (duration: 121.588788ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:42.56292Z","caller":"traceutil/trace.go:171","msg":"trace[1989726021] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:513; }","duration":"113.625432ms","start":"2026-10-13T12:35:42.449267Z","end":"2026-10-13T12:35:42.562893Z","steps":["trace[1989726021] 'read index received' (duration: 52.454624ms)","trace[1989726021] 'applied index is now lower than readState.Index' (duration: 61.169637ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:35:42.563076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.785105ms","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-13T12:35:42.563114Z","caller":"traceutil/trace.go:171","msg":"trace[1053371621] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:494; }","duration":"113.883237ms","start":"2026-10-13T12:35:42.44922Z","end":"2026-10-13T12:35:42.563103Z","steps":["trace[1053371621] 'agreement among raft nodes before linearized reading' (duration: 113.772305ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:42.563436Z","caller":"traceutil/trace.go:171","msg":"trace[2024523295] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"123.918492ms","start":"2026-10-13T12:35:42.439469Z","end":"2026-10-13T12:35:42.563388Z","steps":["trace[2024523295] 'process raft request' (duration: 62.242553ms)","trace[2024523295] 'compare' (duration: 61.006953ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:35:42.563442Z","caller":"traceutil/trace.go:171","msg":"trace[1246543030] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"119.755804ms","start":"2026-10-13T12:35:42.443646Z","end":"2026-10-13T12:35:42.563402Z","steps":["trace[1246543030] 'process raft request' (duration: 119.206962ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:49.160214Z","caller":"traceutil/trace.go:171","msg":"trace[872901060] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"177.733437ms","start":"2026-10-13T12:35:48.982437Z","end":"2026-10-13T12:35:49.16017Z","steps":["trace[872901060] 'process raft request' (duration: 177.468191ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:35:49.383635Z","caller":"traceutil/trace.go:171","msg":"trace[2062888490] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"367.24108ms","start":"2026-10-13T12:35:49.016357Z","end":"2026-10-13T12:35:49.383598Z","steps":["trace[2062888490] 'process raft request' (duration: 366.593155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:49.384191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:49.01632Z","time spent":"367.417644ms","remote":"127.0.0.1:33684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T12:35:49.962384Z","caller":"traceutil/trace.go:171","msg":"trace[493476667] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"454.3099ms","start":"2026-10-13T12:35:49.508049Z","end":"2026-10-13T12:35:49.962358Z","steps":["trace[493476667] 'read index received' (duration: 433.501424ms)","trace[493476667] 'applied index is now lower than readState.Index' (duration: 20.807206ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:35:49.962354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.360485ms","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-13T12:35:49.9625Z","caller":"traceutil/trace.go:171","msg":"trace[1708226583] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:601; }","duration":"122.517358ms","start":"2026-10-13T12:35:49.839948Z","end":"2026-10-13T12:35:49.962465Z","steps":["trace[1708226583] 'range keys from in-memory index tree' (duration: 122.339334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:49.962623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.567936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nhqm5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-13T12:35:49.962577Z","caller":"traceutil/trace.go:171","msg":"trace[1746366651] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"484.273959ms","start":"2026-10-13T12:35:49.478174Z","end":"2026-10-13T12:35:49.962448Z","steps":["trace[1746366651] 'process raft request' (duration: 463.510645ms)","trace[1746366651] 'compare' (duration: 20.260152ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:35:49.962633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.930325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T12:35:49.962827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:49.478142Z","time spent":"484.575236ms","remote":"127.0.0.1:33986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-13T12:35:49.962668Z","caller":"traceutil/trace.go:171","msg":"trace[672327710] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nhqm5; range_end:; response_count:1; response_revision:602; }","duration":"454.667308ms","start":"2026-10-13T12:35:49.507989Z","end":"2026-10-13T12:35:49.962656Z","steps":["trace[672327710] 'agreement among raft nodes before linearized reading' (duration: 454.502974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:35:49.962951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:35:49.507965Z","time spent":"454.918734ms","remote":"127.0.0.1:33784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-nhqm5\" "} {"level":"info","ts":"2026-10-13T12:35:49.962981Z","caller":"traceutil/trace.go:171","msg":"trace[20948859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"131.216402ms","start":"2026-10-13T12:35:49.831645Z","end":"2026-10-13T12:35:49.962861Z","steps":["trace[20948859] 'agreement among raft nodes before linearized reading' (duration: 130.904734ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:36:05.784931Z","caller":"traceutil/trace.go:171","msg":"trace[2090857273] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"148.856433ms","start":"2026-10-13T12:36:05.636052Z","end":"2026-10-13T12:36:05.784909Z","steps":["trace[2090857273] 'process raft request' (duration: 148.71418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:36:06.049889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.029811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:36:06.049965Z","caller":"traceutil/trace.go:171","msg":"trace[1374098150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"219.158204ms","start":"2026-10-13T12:36:05.830791Z","end":"2026-10-13T12:36:06.049949Z","steps":["trace[1374098150] 'range keys from in-memory index tree' (duration: 218.905818ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:37:04.825387Z","caller":"traceutil/trace.go:171","msg":"trace[1637038156] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"159.949521ms","start":"2026-10-13T12:37:04.665413Z","end":"2026-10-13T12:37:04.825362Z","steps":["trace[1637038156] 'process raft request' (duration: 159.811708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:37:05.88624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.148307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-13T12:37:05.88634Z","caller":"traceutil/trace.go:171","msg":"trace[691120650] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1137; }","duration":"124.29421ms","start":"2026-10-13T12:37:05.762025Z","end":"2026-10-13T12:37:05.886319Z","steps":["trace[691120650] 'range keys from in-memory index tree' (duration: 123.955972ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T12:37:17.366177Z","caller":"traceutil/trace.go:171","msg":"trace[412859270] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"333.790487ms","start":"2026-10-13T12:37:17.032359Z","end":"2026-10-13T12:37:17.36615Z","steps":["trace[412859270] 'read index received' (duration: 333.51005ms)","trace[412859270] 'applied index is now lower than readState.Index' (duration: 279.337µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:37:17.366314Z","caller":"traceutil/trace.go:171","msg":"trace[1605377521] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"368.209991ms","start":"2026-10-13T12:37:16.998089Z","end":"2026-10-13T12:37:17.366299Z","steps":["trace[1605377521] 'process raft request' (duration: 367.906763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:37:17.366425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:37:16.998072Z","time spent":"368.274942ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T12:37:17.366632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.258988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-13T12:37:17.366685Z","caller":"traceutil/trace.go:171","msg":"trace[1802823339] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1218; }","duration":"334.33393ms","start":"2026-10-13T12:37:17.032336Z","end":"2026-10-13T12:37:17.36667Z","steps":["trace[1802823339] 'agreement among raft nodes before linearized reading' (duration: 334.151366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:37:17.366725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:37:17.032326Z","time spent":"334.388061ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-10-13T12:37:17.614398Z","caller":"traceutil/trace.go:171","msg":"trace[689014428] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"248.110018ms","start":"2026-10-13T12:37:17.366266Z","end":"2026-10-13T12:37:17.614376Z","steps":["trace[689014428] 'read index received' (duration: 198.002889ms)","trace[689014428] 'applied index is now lower than readState.Index' (duration: 50.105659ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T12:37:17.614528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.584356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T12:37:17.614585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.807068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-13T12:37:17.614597Z","caller":"traceutil/trace.go:171","msg":"trace[1955008978] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1220; }","duration":"433.696548ms","start":"2026-10-13T12:37:17.180883Z","end":"2026-10-13T12:37:17.61458Z","steps":["trace[1955008978] 'agreement among raft nodes before linearized reading' (duration: 433.528774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:37:17.614662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.946127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-10-13T12:37:17.614665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:37:17.180871Z","time spent":"433.765219ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-13T12:37:17.61468Z","caller":"traceutil/trace.go:171","msg":"trace[1306018194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"548.997968ms","start":"2026-10-13T12:37:17.065676Z","end":"2026-10-13T12:37:17.614674Z","steps":["trace[1306018194] 'agreement among raft nodes before linearized reading' (duration: 548.944347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:37:17.614698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:37:17.065659Z","time spent":"549.03503ms","remote":"127.0.0.1:33866","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":"info","ts":"2026-10-13T12:37:17.614704Z","caller":"traceutil/trace.go:171","msg":"trace[9570852] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"445.625477ms","start":"2026-10-13T12:37:17.169042Z","end":"2026-10-13T12:37:17.614668Z","steps":["trace[9570852] 'process raft request' (duration: 395.303193ms)","trace[9570852] 'compare' (duration: 49.805752ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:37:17.614404Z","caller":"traceutil/trace.go:171","msg":"trace[1569133901] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"243.862419ms","start":"2026-10-13T12:37:17.370519Z","end":"2026-10-13T12:37:17.614382Z","steps":["trace[1569133901] 'process raft request' (duration: 243.812508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:37:17.614854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:37:17.169018Z","time spent":"445.750649ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T12:37:17.614609Z","caller":"traceutil/trace.go:171","msg":"trace[1236771840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"441.867788ms","start":"2026-10-13T12:37:17.172732Z","end":"2026-10-13T12:37:17.6146Z","steps":["trace[1236771840] 'agreement among raft nodes before linearized reading' (duration: 441.757076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:37:17.61493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T12:37:17.172713Z","time spent":"442.198127ms","remote":"127.0.0.1:33866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-13T12:41:54.73309Z","caller":"traceutil/trace.go:171","msg":"trace[2141560658] linearizableReadLoop","detail":"{readStateIndex:2625; appliedIndex:2624; }","duration":"110.958892ms","start":"2026-10-13T12:41:54.622104Z","end":"2026-10-13T12:41:54.733063Z","steps":["trace[2141560658] 'read index received' (duration: 110.745567ms)","trace[2141560658] 'applied index is now lower than readState.Index' (duration: 212.075µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T12:41:54.733333Z","caller":"traceutil/trace.go:171","msg":"trace[67121907] transaction","detail":"{read_only:false; response_revision:2518; number_of_response:1; }","duration":"112.032238ms","start":"2026-10-13T12:41:54.621276Z","end":"2026-10-13T12:41:54.733308Z","steps":["trace[67121907] 'process raft request' (duration: 111.652439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T12:41:54.733603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.475984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T12:41:54.733651Z","caller":"traceutil/trace.go:171","msg":"trace[1984420816] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2518; }","duration":"111.562007ms","start":"2026-10-13T12:41:54.622076Z","end":"2026-10-13T12:41:54.733638Z","steps":["trace[1984420816] 'agreement among raft nodes before linearized reading' (duration: 111.086116ms)"],"step_count":1}