{"level":"warn","ts":"2026-10-15T14:18:32.897505Z","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-15T14:18:32.89762Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.96: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.96:2380","--initial-cluster=instance=https://162.253.55.96:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.96:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.96: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-15T14:18:32.897703Z","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-15T14:18:32.897712Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.96:2380"]} {"level":"info","ts":"2026-10-15T14:18:32.897731Z","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-15T14:18:32.898623Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"]} {"level":"info","ts":"2026-10-15T14:18:32.898717Z","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.96:2380"],"listen-peer-urls":["https://162.253.55.96:2380"],"advertise-client-urls":["https://162.253.55.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.96: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-15T14:18:32.90317Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.569335ms"} {"level":"info","ts":"2026-10-15T14:18:32.910937Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"64a80de7883e6886","cluster-id":"8160e60c0578d165"} {"level":"info","ts":"2026-10-15T14:18:32.91101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:18:32.911038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became follower at term 0"} {"level":"info","ts":"2026-10-15T14:18:32.911056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 64a80de7883e6886 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:18:32.911084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became follower at term 1"} {"level":"info","ts":"2026-10-15T14:18:32.911123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=(7253062487954581638)"} {"level":"warn","ts":"2026-10-15T14:18:32.929129Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:18:32.993643Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:18:33.025968Z","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-15T14:18:33.032494Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"64a80de7883e6886","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:18:33.032723Z","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-15T14:18:33.03285Z","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-15T14:18:33.032935Z","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-15T14:18:33.033332Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"64a80de7883e6886","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:18:33.035089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=(7253062487954581638)"} {"level":"info","ts":"2026-10-15T14:18:33.035572Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8160e60c0578d165","local-member-id":"64a80de7883e6886","added-peer-id":"64a80de7883e6886","added-peer-peer-urls":["https://162.253.55.96:2380"]} {"level":"info","ts":"2026-10-15T14:18:33.037627Z","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-15T14:18:33.037902Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"64a80de7883e6886","initial-advertise-peer-urls":["https://162.253.55.96:2380"],"listen-peer-urls":["https://162.253.55.96:2380"],"advertise-client-urls":["https://162.253.55.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:18:33.037928Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:18:33.038031Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.96:2380"} {"level":"info","ts":"2026-10-15T14:18:33.038075Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.96:2380"} {"level":"info","ts":"2026-10-15T14:18:33.611727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:18:33.611802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:18:33.611838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 received MsgPreVoteResp from 64a80de7883e6886 at term 1"} {"level":"info","ts":"2026-10-15T14:18:33.611859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:18:33.61187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 received MsgVoteResp from 64a80de7883e6886 at term 2"} {"level":"info","ts":"2026-10-15T14:18:33.611888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became leader at term 2"} {"level":"info","ts":"2026-10-15T14:18:33.611901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 64a80de7883e6886 elected leader 64a80de7883e6886 at term 2"} {"level":"info","ts":"2026-10-15T14:18:33.613349Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"64a80de7883e6886","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.96:2379]}","request-path":"/0/members/64a80de7883e6886/attributes","cluster-id":"8160e60c0578d165","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:18:33.613579Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:18:33.613707Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:18:33.613748Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:18:33.613645Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:18:33.613811Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:18:33.614824Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8160e60c0578d165","local-member-id":"64a80de7883e6886","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:18:33.614924Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:18:33.61495Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:18:33.617336Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:18:33.617785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.96:2379"} {"level":"info","ts":"2026-10-15T14:18:38.898664Z","caller":"traceutil/trace.go:171","msg":"trace[1450353690] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"301.619273ms","start":"2026-10-15T14:18:38.597008Z","end":"2026-10-15T14:18:38.898627Z","steps":["trace[1450353690] 'read index received' (duration: 301.383328ms)","trace[1450353690] 'applied index is now lower than readState.Index' (duration: 231.485µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:38.898815Z","caller":"traceutil/trace.go:171","msg":"trace[309899655] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"416.33114ms","start":"2026-10-15T14:18:38.482449Z","end":"2026-10-15T14:18:38.89878Z","steps":["trace[309899655] 'process raft request' (duration: 415.964891ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:38.898932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.92116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:18:38.899009Z","caller":"traceutil/trace.go:171","msg":"trace[1501820473] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:279; }","duration":"302.074184ms","start":"2026-10-15T14:18:38.596916Z","end":"2026-10-15T14:18:38.89899Z","steps":["trace[1501820473] 'agreement among raft nodes before linearized reading' (duration: 301.883579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:38.899048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:38.596891Z","time spent":"302.147396ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" "} {"level":"warn","ts":"2026-10-15T14:18:38.89941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:38.482405Z","time spent":"416.480923ms","remote":"127.0.0.1:44832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:18:39.139905Z","caller":"traceutil/trace.go:171","msg":"trace[1667694624] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:285; }","duration":"241.09978ms","start":"2026-10-15T14:18:38.898775Z","end":"2026-10-15T14:18:39.139875Z","steps":["trace[1667694624] 'read index received' (duration: 240.804873ms)","trace[1667694624] 'applied index is now lower than readState.Index' (duration: 292.037µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:39.139957Z","caller":"traceutil/trace.go:171","msg":"trace[1285233510] transaction","detail":"{read_only:false; number_of_response:0; response_revision:280; }","duration":"329.909313ms","start":"2026-10-15T14:18:38.81002Z","end":"2026-10-15T14:18:39.139929Z","steps":["trace[1285233510] 'process raft request' (duration: 329.79205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:39.140124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:38.809973Z","time spent":"330.071447ms","remote":"127.0.0.1:44792","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:39.140143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.378032ms","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-15T14:18:39.140185Z","caller":"traceutil/trace.go:171","msg":"trace[1093780290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:280; }","duration":"362.490045ms","start":"2026-10-15T14:18:38.777683Z","end":"2026-10-15T14:18:39.140173Z","steps":["trace[1093780290] 'agreement among raft nodes before linearized reading' (duration: 362.342681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:39.140219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:38.777654Z","time spent":"362.557627ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T14:18:39.140188Z","caller":"traceutil/trace.go:171","msg":"trace[288002552] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"428.123609ms","start":"2026-10-15T14:18:38.712015Z","end":"2026-10-15T14:18:39.140138Z","steps":["trace[288002552] 'process raft request' (duration: 427.632707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:39.140329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:38.711999Z","time spent":"428.260583ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:39.140388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.115682ms","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-15T14:18:39.140431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.964466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-10-15T14:18:39.140442Z","caller":"traceutil/trace.go:171","msg":"trace[1580555014] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:280; }","duration":"429.210605ms","start":"2026-10-15T14:18:38.711215Z","end":"2026-10-15T14:18:39.140425Z","steps":["trace[1580555014] 'agreement among raft nodes before linearized reading' (duration: 429.109712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:39.14045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.73993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-10-15T14:18:39.140479Z","caller":"traceutil/trace.go:171","msg":"trace[1084645618] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:280; }","duration":"428.068488ms","start":"2026-10-15T14:18:38.712396Z","end":"2026-10-15T14:18:39.140465Z","steps":["trace[1084645618] 'agreement among raft nodes before linearized reading' (duration: 427.961545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:39.14048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:38.711193Z","time spent":"429.280097ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-10-15T14:18:39.140511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:38.712365Z","time spent":"428.13983ms","remote":"127.0.0.1:44986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-10-15T14:18:39.140518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.019907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:18:39.140545Z","caller":"traceutil/trace.go:171","msg":"trace[340780736] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:280; }","duration":"105.107059ms","start":"2026-10-15T14:18:39.035427Z","end":"2026-10-15T14:18:39.140534Z","steps":["trace[340780736] 'agreement among raft nodes before linearized reading' (duration: 105.070609ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:39.140481Z","caller":"traceutil/trace.go:171","msg":"trace[651272212] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:280; }","duration":"237.812282ms","start":"2026-10-15T14:18:38.902659Z","end":"2026-10-15T14:18:39.140471Z","steps":["trace[651272212] 'agreement among raft nodes before linearized reading' (duration: 237.754581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:39.140668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.450901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:18:39.140708Z","caller":"traceutil/trace.go:171","msg":"trace[136135935] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:280; }","duration":"231.511713ms","start":"2026-10-15T14:18:38.909185Z","end":"2026-10-15T14:18:39.140697Z","steps":["trace[136135935] 'agreement among raft nodes before linearized reading' (duration: 231.438872ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:22.563908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.248362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:22.564221Z","caller":"traceutil/trace.go:171","msg":"trace[366769485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"100.648002ms","start":"2026-10-15T14:19:22.463552Z","end":"2026-10-15T14:19:22.5642Z","steps":["trace[366769485] 'range keys from in-memory index tree' (duration: 100.113179ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:22.922005Z","caller":"traceutil/trace.go:171","msg":"trace[1351587750] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"266.02825ms","start":"2026-10-15T14:19:22.65592Z","end":"2026-10-15T14:19:22.921949Z","steps":["trace[1351587750] 'process raft request' (duration: 264.781511ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:23.584644Z","caller":"traceutil/trace.go:171","msg":"trace[1486904985] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"121.831345ms","start":"2026-10-15T14:19:23.462774Z","end":"2026-10-15T14:19:23.584605Z","steps":["trace[1486904985] 'read index received' (duration: 121.658271ms)","trace[1486904985] 'applied index is now lower than readState.Index' (duration: 171.364µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:23.584836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.04481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:23.584814Z","caller":"traceutil/trace.go:171","msg":"trace[1993893790] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"163.887674ms","start":"2026-10-15T14:19:23.420855Z","end":"2026-10-15T14:19:23.584742Z","steps":["trace[1993893790] 'process raft request' (duration: 163.559166ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:23.584947Z","caller":"traceutil/trace.go:171","msg":"trace[1278130425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"122.242534ms","start":"2026-10-15T14:19:23.462683Z","end":"2026-10-15T14:19:23.584926Z","steps":["trace[1278130425] 'agreement among raft nodes before linearized reading' (duration: 122.06738ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:23.632739Z","caller":"traceutil/trace.go:171","msg":"trace[1366808619] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"134.48584ms","start":"2026-10-15T14:19:23.498193Z","end":"2026-10-15T14:19:23.632679Z","steps":["trace[1366808619] 'process raft request' (duration: 119.501621ms)","trace[1366808619] 'compare' (duration: 14.442936ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:23.632834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.491987ms","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-15T14:19:23.632928Z","caller":"traceutil/trace.go:171","msg":"trace[999925447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"127.63085ms","start":"2026-10-15T14:19:23.50528Z","end":"2026-10-15T14:19:23.632911Z","steps":["trace[999925447] 'agreement among raft nodes before linearized reading' (duration: 127.402795ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:38.511812Z","caller":"traceutil/trace.go:171","msg":"trace[14243216] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"101.39448ms","start":"2026-10-15T14:19:38.410392Z","end":"2026-10-15T14:19:38.511786Z","steps":["trace[14243216] 'process raft request' (duration: 101.184295ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:19.72782Z","caller":"traceutil/trace.go:171","msg":"trace[652572515] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"392.411392ms","start":"2026-10-15T14:20:19.335387Z","end":"2026-10-15T14:20:19.727799Z","steps":["trace[652572515] 'read index received' (duration: 392.309729ms)","trace[652572515] 'applied index is now lower than readState.Index' (duration: 100.603µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:20:19.727917Z","caller":"traceutil/trace.go:171","msg":"trace[54026406] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"590.939991ms","start":"2026-10-15T14:20:19.136969Z","end":"2026-10-15T14:20:19.727909Z","steps":["trace[54026406] 'process raft request' (duration: 590.713376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:19.728119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.571643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:20:19.728204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.815561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:20:19.728205Z","caller":"traceutil/trace.go:171","msg":"trace[1757386132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"266.710087ms","start":"2026-10-15T14:20:19.461477Z","end":"2026-10-15T14:20:19.728187Z","steps":["trace[1757386132] 'agreement among raft nodes before linearized reading' (duration: 266.550883ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:19.728232Z","caller":"traceutil/trace.go:171","msg":"trace[617240142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"392.875702ms","start":"2026-10-15T14:20:19.335347Z","end":"2026-10-15T14:20:19.728223Z","steps":["trace[617240142] 'agreement among raft nodes before linearized reading' (duration: 392.831741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:19.72825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:20:19.335331Z","time spent":"392.915803ms","remote":"127.0.0.1:44646","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-15T14:20:19.728464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.65736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-15T14:20:19.728503Z","caller":"traceutil/trace.go:171","msg":"trace[1387268482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1094; }","duration":"197.720671ms","start":"2026-10-15T14:20:19.530773Z","end":"2026-10-15T14:20:19.728493Z","steps":["trace[1387268482] 'agreement among raft nodes before linearized reading' (duration: 197.610748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:19.728148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:20:19.136953Z","time spent":"590.981143ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:20:20.017322Z","caller":"traceutil/trace.go:171","msg":"trace[1639604621] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"150.062772ms","start":"2026-10-15T14:20:19.867229Z","end":"2026-10-15T14:20:20.017292Z","steps":["trace[1639604621] 'read index received' (duration: 111.632948ms)","trace[1639604621] 'applied index is now lower than readState.Index' (duration: 38.428534ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:20:20.017396Z","caller":"traceutil/trace.go:171","msg":"trace[1748438125] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"285.375671ms","start":"2026-10-15T14:20:19.731993Z","end":"2026-10-15T14:20:20.017368Z","steps":["trace[1748438125] 'process raft request' (duration: 246.939726ms)","trace[1748438125] 'compare' (duration: 38.22877ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:20:20.01757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.329958ms","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-15T14:20:20.017618Z","caller":"traceutil/trace.go:171","msg":"trace[1180209678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"150.43186ms","start":"2026-10-15T14:20:19.867172Z","end":"2026-10-15T14:20:20.017604Z","steps":["trace[1180209678] 'agreement among raft nodes before linearized reading' (duration: 150.254956ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:36.959783Z","caller":"traceutil/trace.go:171","msg":"trace[1674349469] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"172.9219ms","start":"2026-10-15T14:20:36.786827Z","end":"2026-10-15T14:20:36.959749Z","steps":["trace[1674349469] 'process raft request' (duration: 172.717995ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:40.705452Z","caller":"traceutil/trace.go:171","msg":"trace[667268298] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"131.012269ms","start":"2026-10-15T14:20:40.574418Z","end":"2026-10-15T14:20:40.70543Z","steps":["trace[667268298] 'process raft request' (duration: 130.894027ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:41.157304Z","caller":"traceutil/trace.go:171","msg":"trace[1836612244] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"179.835142ms","start":"2026-10-15T14:20:40.977444Z","end":"2026-10-15T14:20:41.157279Z","steps":["trace[1836612244] 'process raft request' (duration: 179.669788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:41.36294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.0493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:20:41.363015Z","caller":"traceutil/trace.go:171","msg":"trace[1630876896] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1200; }","duration":"137.170623ms","start":"2026-10-15T14:20:41.225829Z","end":"2026-10-15T14:20:41.362999Z","steps":["trace[1630876896] 'count revisions from in-memory index tree' (duration: 136.985859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:41.712565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.443924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:20:41.712657Z","caller":"traceutil/trace.go:171","msg":"trace[408681758] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1202; }","duration":"179.602928ms","start":"2026-10-15T14:20:41.533032Z","end":"2026-10-15T14:20:41.712635Z","steps":["trace[408681758] 'range keys from in-memory index tree' (duration: 179.378992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:41.71267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.102796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:20:41.712734Z","caller":"traceutil/trace.go:171","msg":"trace[335010174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"249.206748ms","start":"2026-10-15T14:20:41.463512Z","end":"2026-10-15T14:20:41.712719Z","steps":["trace[335010174] 'range keys from in-memory index tree' (duration: 249.034794ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:41.750231Z","caller":"traceutil/trace.go:171","msg":"trace[25606726] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"132.804471ms","start":"2026-10-15T14:20:41.617384Z","end":"2026-10-15T14:20:41.750188Z","steps":["trace[25606726] 'process raft request' (duration: 132.608406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:42.329012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.67395ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T14:20:42.329166Z","caller":"traceutil/trace.go:171","msg":"trace[1692540924] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"162.379584ms","start":"2026-10-15T14:20:42.166766Z","end":"2026-10-15T14:20:42.329145Z","steps":["trace[1692540924] 'read index received' (duration: 52.407368ms)","trace[1692540924] 'applied index is now lower than readState.Index' (duration: 109.970546ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:20:42.329435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.653441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-15T14:20:42.329484Z","caller":"traceutil/trace.go:171","msg":"trace[1008786424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1209; }","duration":"162.751393ms","start":"2026-10-15T14:20:42.16672Z","end":"2026-10-15T14:20:42.329471Z","steps":["trace[1008786424] 'agreement among raft nodes before linearized reading' (duration: 162.542858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:42.329851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.238763ms","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-15T14:20:42.329907Z","caller":"traceutil/trace.go:171","msg":"trace[1802214773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"148.338395ms","start":"2026-10-15T14:20:42.181557Z","end":"2026-10-15T14:20:42.329896Z","steps":["trace[1802214773] 'agreement among raft nodes before linearized reading' (duration: 148.193992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:42.33026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.529875ms","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-15T14:20:42.330306Z","caller":"traceutil/trace.go:171","msg":"trace[667323523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"141.595787ms","start":"2026-10-15T14:20:42.1887Z","end":"2026-10-15T14:20:42.330296Z","steps":["trace[667323523] 'agreement among raft nodes before linearized reading' (duration: 141.454074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:42.330524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.982656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-10-15T14:20:42.330569Z","caller":"traceutil/trace.go:171","msg":"trace[1206645144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"142.071388ms","start":"2026-10-15T14:20:42.188487Z","end":"2026-10-15T14:20:42.330558Z","steps":["trace[1206645144] 'agreement among raft nodes before linearized reading' (duration: 141.967836ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:50.480163Z","caller":"traceutil/trace.go:171","msg":"trace[1725685872] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"104.368404ms","start":"2026-10-15T14:20:50.375768Z","end":"2026-10-15T14:20:50.480137Z","steps":["trace[1725685872] 'read index received' (duration: 104.072088ms)","trace[1725685872] 'applied index is now lower than readState.Index' (duration: 294.986µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:20:50.480542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.138103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-10-15T14:20:50.480535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.942741ms","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-15T14:20:50.480753Z","caller":"traceutil/trace.go:171","msg":"trace[569141438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"103.219198ms","start":"2026-10-15T14:20:50.377522Z","end":"2026-10-15T14:20:50.480741Z","steps":["trace[569141438] 'agreement among raft nodes before linearized reading' (duration: 102.811438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:50.48058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.787324ms","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-15T14:20:50.480898Z","caller":"traceutil/trace.go:171","msg":"trace[1655089384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"105.132513ms","start":"2026-10-15T14:20:50.375745Z","end":"2026-10-15T14:20:50.480877Z","steps":["trace[1655089384] 'agreement among raft nodes before linearized reading' (duration: 104.545129ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:50.480884Z","caller":"traceutil/trace.go:171","msg":"trace[605226586] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"104.627901ms","start":"2026-10-15T14:20:50.375654Z","end":"2026-10-15T14:20:50.480282Z","steps":["trace[605226586] 'process raft request' (duration: 104.302213ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:50.480677Z","caller":"traceutil/trace.go:171","msg":"trace[1342880724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"102.319707ms","start":"2026-10-15T14:20:50.378342Z","end":"2026-10-15T14:20:50.480661Z","steps":["trace[1342880724] 'agreement among raft nodes before linearized reading' (duration: 102.04985ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:50.802503Z","caller":"traceutil/trace.go:171","msg":"trace[764966061] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"316.633538ms","start":"2026-10-15T14:20:50.485846Z","end":"2026-10-15T14:20:50.80248Z","steps":["trace[764966061] 'process raft request' (duration: 316.287409ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:50.802604Z","caller":"traceutil/trace.go:171","msg":"trace[1757121383] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"316.32316ms","start":"2026-10-15T14:20:50.486256Z","end":"2026-10-15T14:20:50.802579Z","steps":["trace[1757121383] 'process raft request' (duration: 316.158066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:50.802643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:20:50.485821Z","time spent":"316.74755ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:20:50.803103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:20:50.486241Z","time spent":"316.438152ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}