{"level":"warn","ts":"2026-02-13T17:21:29.487935Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-13T17:21:29.488081Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-02-13T17:21:29.488171Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-13T17:21:29.4882Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-13T17:21:29.488246Z","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-02-13T17:21:29.488983Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-13T17:21:29.489079Z","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":2,"max-cpu-available":2,"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.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-02-13T17:21:29.494183Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.825162ms"} {"level":"info","ts":"2026-02-13T17:21:29.513339Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-13T17:21:29.513481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T17:21:29.513555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-13T17:21:29.513623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T17:21:29.513637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-13T17:21:29.513678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-13T17:21:29.523263Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T17:21:29.529099Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T17:21:29.539623Z","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-02-13T17:21:29.556385Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T17:21:29.55717Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T17:21:29.557391Z","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-02-13T17:21:29.557449Z","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-02-13T17:21:29.557475Z","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-02-13T17:21:29.575776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-13T17:21:29.575972Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-13T17:21:29.579899Z","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-02-13T17:21:29.580108Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T17:21:29.580131Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T17:21:29.58063Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-13T17:21:29.580642Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-13T17:21:29.915796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T17:21:29.915914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T17:21:29.915942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-13T17:21:29.915967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-13T17:21:29.916007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-13T17:21:29.916035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-13T17:21:29.916071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-13T17:21:29.918521Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T17:21:29.918657Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T17:21:29.918985Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T17:21:29.919082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T17:21:29.922685Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T17:21:29.92272Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T17:21:29.925202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T17:21:29.92545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-02-13T17:21:29.927822Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T17:21:29.935638Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T17:21:29.935666Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-13T17:21:42.481079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.00267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-13T17:21:42.481169Z","caller":"traceutil/trace.go:171","msg":"trace[499646542] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"135.914902ms","start":"2026-02-13T17:21:42.345229Z","end":"2026-02-13T17:21:42.481144Z","steps":["trace[499646542] 'read index received' (duration: 126.773815ms)","trace[499646542] 'applied index is now lower than readState.Index' (duration: 9.140197ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T17:21:42.48122Z","caller":"traceutil/trace.go:171","msg":"trace[1858958363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"241.205534ms","start":"2026-02-13T17:21:42.239989Z","end":"2026-02-13T17:21:42.481195Z","steps":["trace[1858958363] 'range keys from in-memory index tree' (duration: 240.908199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:42.481322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.083966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-13T17:21:42.481347Z","caller":"traceutil/trace.go:171","msg":"trace[1250515811] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:354; }","duration":"136.173387ms","start":"2026-02-13T17:21:42.345164Z","end":"2026-02-13T17:21:42.481338Z","steps":["trace[1250515811] 'agreement among raft nodes before linearized reading' (duration: 136.080585ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:21:42.481707Z","caller":"traceutil/trace.go:171","msg":"trace[479436421] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"167.088544ms","start":"2026-02-13T17:21:42.314603Z","end":"2026-02-13T17:21:42.481691Z","steps":["trace[479436421] 'process raft request' (duration: 157.388647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.212729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811870433029744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T17:21:48.713433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811870433029744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T17:21:48.868124Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.158604838s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T17:21:48.911502Z","caller":"traceutil/trace.go:171","msg":"trace[1135736967] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"1.202021635s","start":"2026-02-13T17:21:47.709449Z","end":"2026-02-13T17:21:48.911471Z","steps":["trace[1135736967] 'process raft request' (duration: 1.158939484s)","trace[1135736967] 'compare' (duration: 42.935728ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T17:21:48.911985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:21:47.709432Z","time spent":"1.202152987s","remote":"127.0.0.1:50924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T17:21:48.914978Z","caller":"traceutil/trace.go:171","msg":"trace[444888831] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"1.20288161s","start":"2026-02-13T17:21:47.712083Z","end":"2026-02-13T17:21:48.914965Z","steps":["trace[444888831] 'process raft request' (duration: 1.202463922s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.915062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:21:47.712046Z","time spent":"1.202971852s","remote":"127.0.0.1:50924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T17:21:48.915263Z","caller":"traceutil/trace.go:171","msg":"trace[339537817] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"1.203055203s","start":"2026-02-13T17:21:47.712202Z","end":"2026-02-13T17:21:48.915257Z","steps":["trace[339537817] 'process raft request' (duration: 1.202422782s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.91546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:21:47.712197Z","time spent":"1.203088533s","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T17:21:48.916749Z","caller":"traceutil/trace.go:171","msg":"trace[619976588] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"1.202551523s","start":"2026-02-13T17:21:47.714188Z","end":"2026-02-13T17:21:48.916739Z","steps":["trace[619976588] 'process raft request' (duration: 1.200488567s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.9199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:21:47.714181Z","time spent":"1.202595364s","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3773,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T17:21:48.929003Z","caller":"traceutil/trace.go:171","msg":"trace[823082141] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:398; }","duration":"1.216705981s","start":"2026-02-13T17:21:47.712271Z","end":"2026-02-13T17:21:48.928977Z","steps":["trace[823082141] 'read index received' (duration: 1.156246916s)","trace[823082141] 'applied index is now lower than readState.Index' (duration: 60.457595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T17:21:48.929197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216898114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-13T17:21:48.929251Z","caller":"traceutil/trace.go:171","msg":"trace[1103700372] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:389; }","duration":"1.216975035s","start":"2026-02-13T17:21:47.712266Z","end":"2026-02-13T17:21:48.929241Z","steps":["trace[1103700372] 'agreement among raft nodes before linearized reading' (duration: 1.216814092s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.92929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:21:47.712232Z","time spent":"1.217052387s","remote":"127.0.0.1:51344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3812,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"warn","ts":"2026-02-13T17:21:48.948453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.933177ms","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-02-13T17:21:48.948532Z","caller":"traceutil/trace.go:171","msg":"trace[1827767093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"434.07945ms","start":"2026-02-13T17:21:48.514436Z","end":"2026-02-13T17:21:48.948515Z","steps":["trace[1827767093] 'agreement among raft nodes before linearized reading' (duration: 433.932537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.948561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:21:48.514386Z","time spent":"434.169361ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T17:21:48.948855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.787987ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:21:48.948919Z","caller":"traceutil/trace.go:171","msg":"trace[1787931371] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:391; }","duration":"690.855819ms","start":"2026-02-13T17:21:48.258057Z","end":"2026-02-13T17:21:48.948912Z","steps":["trace[1787931371] 'agreement among raft nodes before linearized reading' (duration: 690.778907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.958424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.971364ms","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-02-13T17:21:48.958506Z","caller":"traceutil/trace.go:171","msg":"trace[1008453520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"884.131877ms","start":"2026-02-13T17:21:48.074357Z","end":"2026-02-13T17:21:48.958489Z","steps":["trace[1008453520] 'agreement among raft nodes before linearized reading' (duration: 883.944604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.958543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:21:48.074329Z","time spent":"884.207548ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T17:21:48.958887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.303097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:21:48.958924Z","caller":"traceutil/trace.go:171","msg":"trace[554900822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"896.367009ms","start":"2026-02-13T17:21:48.062549Z","end":"2026-02-13T17:21:48.958916Z","steps":["trace[554900822] 'agreement among raft nodes before linearized reading' (duration: 885.789748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:48.958941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:21:48.062532Z","time spent":"896.405419ms","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T17:21:49.223212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.356513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T17:21:49.225468Z","caller":"traceutil/trace.go:171","msg":"trace[1510365331] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"254.006622ms","start":"2026-02-13T17:21:48.971433Z","end":"2026-02-13T17:21:49.22544Z","steps":["trace[1510365331] 'process raft request' (duration: 94.088382ms)","trace[1510365331] 'compare' (duration: 156.016158ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T17:21:49.226521Z","caller":"traceutil/trace.go:171","msg":"trace[1513688116] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"239.474758ms","start":"2026-02-13T17:21:48.987033Z","end":"2026-02-13T17:21:49.226508Z","steps":["trace[1513688116] 'read index received' (duration: 78.501609ms)","trace[1513688116] 'applied index is now lower than readState.Index' (duration: 160.971129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T17:21:49.226658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.61705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-13T17:21:49.226684Z","caller":"traceutil/trace.go:171","msg":"trace[2072878816] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:394; }","duration":"239.655051ms","start":"2026-02-13T17:21:48.987019Z","end":"2026-02-13T17:21:49.226674Z","steps":["trace[2072878816] 'agreement among raft nodes before linearized reading' (duration: 239.570749ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:21:49.231159Z","caller":"traceutil/trace.go:171","msg":"trace[1432231897] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"243.742806ms","start":"2026-02-13T17:21:48.987401Z","end":"2026-02-13T17:21:49.231144Z","steps":["trace[1432231897] 'process raft request' (duration: 240.425515ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:21:49.233778Z","caller":"traceutil/trace.go:171","msg":"trace[2045070247] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"246.070007ms","start":"2026-02-13T17:21:48.987699Z","end":"2026-02-13T17:21:49.233769Z","steps":["trace[2045070247] 'process raft request' (duration: 240.390465ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:21:49.234517Z","caller":"traceutil/trace.go:171","msg":"trace[96558681] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"239.524219ms","start":"2026-02-13T17:21:48.994984Z","end":"2026-02-13T17:21:49.234509Z","steps":["trace[96558681] 'process raft request' (duration: 233.153415ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:21:49.234714Z","caller":"traceutil/trace.go:171","msg":"trace[1617979576] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"239.56591ms","start":"2026-02-13T17:21:48.995142Z","end":"2026-02-13T17:21:49.234708Z","steps":["trace[1617979576] 'process raft request' (duration: 233.032352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:49.234929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.626977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:21:49.234956Z","caller":"traceutil/trace.go:171","msg":"trace[1488808633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:398; }","duration":"163.713968ms","start":"2026-02-13T17:21:49.071235Z","end":"2026-02-13T17:21:49.234949Z","steps":["trace[1488808633] 'agreement among raft nodes before linearized reading' (duration: 163.642207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:21:49.235402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.986934ms","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-02-13T17:21:49.235424Z","caller":"traceutil/trace.go:171","msg":"trace[1737771396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"226.052215ms","start":"2026-02-13T17:21:49.009367Z","end":"2026-02-13T17:21:49.235419Z","steps":["trace[1737771396] 'agreement among raft nodes before linearized reading' (duration: 218.849155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:13.897073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811870433030029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T17:22:14.391505Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.00344957s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-13T17:22:14.391809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:13.387933Z","time spent":"1.003872697s","remote":"127.0.0.1:50888","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-13T17:22:15.282353Z","caller":"traceutil/trace.go:171","msg":"trace[1132069011] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"889.206331ms","start":"2026-02-13T17:22:14.393133Z","end":"2026-02-13T17:22:15.28234Z","steps":["trace[1132069011] 'process raft request' (duration: 889.16898ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:22:15.282403Z","caller":"traceutil/trace.go:171","msg":"trace[869300546] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.802955293s","start":"2026-02-13T17:22:13.479435Z","end":"2026-02-13T17:22:15.282391Z","steps":["trace[869300546] 'process raft request' (duration: 1.773850363s)","trace[869300546] 'compare' (duration: 28.786069ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T17:22:15.282435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:14.39312Z","time spent":"889.271173ms","remote":"127.0.0.1:50888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T17:22:15.282472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:13.479418Z","time spent":"1.803011464s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T17:22:15.282529Z","caller":"traceutil/trace.go:171","msg":"trace[817393226] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"1.886023058s","start":"2026-02-13T17:22:13.396501Z","end":"2026-02-13T17:22:15.282524Z","steps":["trace[817393226] 'read index received' (duration: 995.555751ms)","trace[817393226] 'applied index is now lower than readState.Index' (duration: 890.466736ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T17:22:15.282789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.886282147s","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-02-13T17:22:15.282819Z","caller":"traceutil/trace.go:171","msg":"trace[1799432915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"1.886334348s","start":"2026-02-13T17:22:13.39647Z","end":"2026-02-13T17:22:15.282804Z","steps":["trace[1799432915] 'agreement among raft nodes before linearized reading' (duration: 1.8861016s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:15.282835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:13.396457Z","time spent":"1.886374389s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T17:22:15.283065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870487245s","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-02-13T17:22:15.283079Z","caller":"traceutil/trace.go:171","msg":"trace[1906821649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"1.870531106s","start":"2026-02-13T17:22:13.412543Z","end":"2026-02-13T17:22:15.283074Z","steps":["trace[1906821649] 'agreement among raft nodes before linearized reading' (duration: 1.870487404s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:15.283097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:13.412522Z","time spent":"1.870567878s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T17:22:15.283543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221283304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:15.283557Z","caller":"traceutil/trace.go:171","msg":"trace[282184259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"1.221330385s","start":"2026-02-13T17:22:14.062222Z","end":"2026-02-13T17:22:15.283552Z","steps":["trace[282184259] 'agreement among raft nodes before linearized reading' (duration: 1.221242812s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:15.283573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:14.062205Z","time spent":"1.221364996s","remote":"127.0.0.1:50876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T17:22:15.285412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586487043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-13T17:22:15.297448Z","caller":"traceutil/trace.go:171","msg":"trace[991637561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"1.598541684s","start":"2026-02-13T17:22:13.69889Z","end":"2026-02-13T17:22:15.297432Z","steps":["trace[991637561] 'agreement among raft nodes before linearized reading' (duration: 1.586480092s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:15.297513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:13.698877Z","time spent":"1.598618126s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T17:22:15.286403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.872497933s","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-02-13T17:22:15.297955Z","caller":"traceutil/trace.go:171","msg":"trace[605793948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"1.883958262s","start":"2026-02-13T17:22:13.413875Z","end":"2026-02-13T17:22:15.297833Z","steps":["trace[605793948] 'agreement among raft nodes before linearized reading' (duration: 1.872466842s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:15.29803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:13.413849Z","time spent":"1.884129877s","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T17:22:18.210311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811870433030082,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T17:22:18.227165Z","caller":"traceutil/trace.go:171","msg":"trace[285031880] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"540.176164ms","start":"2026-02-13T17:22:17.68697Z","end":"2026-02-13T17:22:18.227147Z","steps":["trace[285031880] 'process raft request' (duration: 540.0555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.227428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.686927Z","time spent":"540.454216ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T17:22:18.243279Z","caller":"traceutil/trace.go:171","msg":"trace[1663563839] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"533.477305ms","start":"2026-02-13T17:22:17.70979Z","end":"2026-02-13T17:22:18.243268Z","steps":["trace[1663563839] 'read index received' (duration: 518.507832ms)","trace[1663563839] 'applied index is now lower than readState.Index' (duration: 14.968743ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T17:22:18.243477Z","caller":"traceutil/trace.go:171","msg":"trace[955432992] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"494.97846ms","start":"2026-02-13T17:22:17.748485Z","end":"2026-02-13T17:22:18.243463Z","steps":["trace[955432992] 'process raft request' (duration: 494.718191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.243576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.748475Z","time spent":"495.042952ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T17:22:18.243581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.776246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.243662Z","caller":"traceutil/trace.go:171","msg":"trace[1267905494] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:553; }","duration":"533.866309ms","start":"2026-02-13T17:22:17.709787Z","end":"2026-02-13T17:22:18.243654Z","steps":["trace[1267905494] 'agreement among raft nodes before linearized reading' (duration: 533.763575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.244343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.709758Z","time spent":"534.577333ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.246966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.61053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.247Z","caller":"traceutil/trace.go:171","msg":"trace[1805107879] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"535.655281ms","start":"2026-02-13T17:22:17.711336Z","end":"2026-02-13T17:22:18.246991Z","steps":["trace[1805107879] 'agreement among raft nodes before linearized reading' (duration: 535.597489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.247084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.71133Z","time spent":"535.747954ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.247067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.235933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.247293Z","caller":"traceutil/trace.go:171","msg":"trace[1456671054] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"379.523033ms","start":"2026-02-13T17:22:17.867721Z","end":"2026-02-13T17:22:18.247244Z","steps":["trace[1456671054] 'agreement among raft nodes before linearized reading' (duration: 379.237963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.24731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.873376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.247422Z","caller":"traceutil/trace.go:171","msg":"trace[967817837] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"500.95987ms","start":"2026-02-13T17:22:17.746421Z","end":"2026-02-13T17:22:18.247381Z","steps":["trace[967817837] 'agreement among raft nodes before linearized reading' (duration: 500.871717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.247446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.746417Z","time spent":"501.022272ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.24756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.375251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.247579Z","caller":"traceutil/trace.go:171","msg":"trace[2132881385] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"508.403573ms","start":"2026-02-13T17:22:17.739171Z","end":"2026-02-13T17:22:18.247575Z","steps":["trace[2132881385] 'agreement among raft nodes before linearized reading' (duration: 508.358701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.247616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.739166Z","time spent":"508.445024ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.247721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.997994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.247739Z","caller":"traceutil/trace.go:171","msg":"trace[1410532457] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"511.031705ms","start":"2026-02-13T17:22:17.736703Z","end":"2026-02-13T17:22:18.247735Z","steps":["trace[1410532457] 'agreement among raft nodes before linearized reading' (duration: 511.003044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.247753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.736699Z","time spent":"511.050756ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.247843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.187576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.247879Z","caller":"traceutil/trace.go:171","msg":"trace[687817390] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"514.245378ms","start":"2026-02-13T17:22:17.733629Z","end":"2026-02-13T17:22:18.247874Z","steps":["trace[687817390] 'agreement among raft nodes before linearized reading' (duration: 514.202136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.247906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.733616Z","time spent":"514.284639ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.248009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.052025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.248029Z","caller":"traceutil/trace.go:171","msg":"trace[1516739157] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"534.084386ms","start":"2026-02-13T17:22:17.713941Z","end":"2026-02-13T17:22:18.248025Z","steps":["trace[1516739157] 'agreement among raft nodes before linearized reading' (duration: 534.053215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.248042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.713936Z","time spent":"534.102997ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.248163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.867711Z","time spent":"380.441186ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.248225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.907959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.248597Z","caller":"traceutil/trace.go:171","msg":"trace[664793616] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"536.297784ms","start":"2026-02-13T17:22:17.712289Z","end":"2026-02-13T17:22:18.248587Z","steps":["trace[664793616] 'agreement among raft nodes before linearized reading' (duration: 535.89428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.250727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:22:17.712284Z","time spent":"538.431018ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-13T17:22:18.248715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.504121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.250882Z","caller":"traceutil/trace.go:171","msg":"trace[990393670] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"182.693168ms","start":"2026-02-13T17:22:18.068181Z","end":"2026-02-13T17:22:18.250874Z","steps":["trace[990393670] 'agreement among raft nodes before linearized reading' (duration: 180.431499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:22:18.248505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.220182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:22:18.251436Z","caller":"traceutil/trace.go:171","msg":"trace[213969031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"189.164894ms","start":"2026-02-13T17:22:18.062265Z","end":"2026-02-13T17:22:18.25143Z","steps":["trace[213969031] 'agreement among raft nodes before linearized reading' (duration: 186.218161ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:22:19.916765Z","caller":"traceutil/trace.go:171","msg":"trace[1370845421] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"225.528108ms","start":"2026-02-13T17:22:19.691221Z","end":"2026-02-13T17:22:19.916749Z","steps":["trace[1370845421] 'process raft request' (duration: 225.434235ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:23:37.04333Z","caller":"traceutil/trace.go:171","msg":"trace[230587119] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:981; }","duration":"422.429345ms","start":"2026-02-13T17:23:36.620872Z","end":"2026-02-13T17:23:37.043301Z","steps":["trace[230587119] 'read index received' (duration: 422.407814ms)","trace[230587119] 'applied index is now lower than readState.Index' (duration: 20.081µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T17:23:37.043572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.680601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-02-13T17:23:37.043634Z","caller":"traceutil/trace.go:171","msg":"trace[375717820] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:943; }","duration":"422.780353ms","start":"2026-02-13T17:23:36.620844Z","end":"2026-02-13T17:23:37.043625Z","steps":["trace[375717820] 'agreement among raft nodes before linearized reading' (duration: 422.573649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:23:37.043673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:23:36.620834Z","time spent":"422.831184ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2500,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-02-13T17:23:37.043809Z","caller":"traceutil/trace.go:171","msg":"trace[310285838] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"432.675637ms","start":"2026-02-13T17:23:36.611113Z","end":"2026-02-13T17:23:37.043788Z","steps":["trace[310285838] 'process raft request' (duration: 428.185471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:23:37.044028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:23:36.6111Z","time spent":"432.840601ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T17:23:37.044942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.843157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:23:37.044994Z","caller":"traceutil/trace.go:171","msg":"trace[1103667057] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:944; }","duration":"164.935228ms","start":"2026-02-13T17:23:36.880045Z","end":"2026-02-13T17:23:37.04498Z","steps":["trace[1103667057] 'agreement among raft nodes before linearized reading' (duration: 164.848266ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:23:37.045343Z","caller":"traceutil/trace.go:171","msg":"trace[1386940438] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"264.694625ms","start":"2026-02-13T17:23:36.780635Z","end":"2026-02-13T17:23:37.04533Z","steps":["trace[1386940438] 'process raft request' (duration: 264.164583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:23:44.200773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:23:43.394004Z","time spent":"806.761327ms","remote":"127.0.0.1:50888","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-13T17:23:44.200821Z","caller":"traceutil/trace.go:171","msg":"trace[942209884] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"388.259006ms","start":"2026-02-13T17:23:43.812477Z","end":"2026-02-13T17:23:44.200736Z","steps":["trace[942209884] 'read index received' (duration: 388.138113ms)","trace[942209884] 'applied index is now lower than readState.Index' (duration: 119.683µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T17:23:44.201032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.538353ms","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-02-13T17:23:44.201064Z","caller":"traceutil/trace.go:171","msg":"trace[185082749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"388.615414ms","start":"2026-02-13T17:23:43.812438Z","end":"2026-02-13T17:23:44.201053Z","steps":["trace[185082749] 'agreement among raft nodes before linearized reading' (duration: 388.492821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:23:44.201095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:23:43.81242Z","time spent":"388.667066ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T17:23:44.201565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.441677ms","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-02-13T17:23:44.201656Z","caller":"traceutil/trace.go:171","msg":"trace[1394328515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"384.6026ms","start":"2026-02-13T17:23:43.81703Z","end":"2026-02-13T17:23:44.201632Z","steps":["trace[1394328515] 'agreement among raft nodes before linearized reading' (duration: 384.17337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:23:44.20174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T17:23:43.81702Z","time spent":"384.709692ms","remote":"127.0.0.1:51126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T17:23:44.20241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.123501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:23:44.202612Z","caller":"traceutil/trace.go:171","msg":"trace[145527260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"138.347355ms","start":"2026-02-13T17:23:44.064251Z","end":"2026-02-13T17:23:44.202599Z","steps":["trace[145527260] 'agreement among raft nodes before linearized reading' (duration: 138.13524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:23:44.202932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.391383ms","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-02-13T17:23:44.203097Z","caller":"traceutil/trace.go:171","msg":"trace[1481124223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"237.547446ms","start":"2026-02-13T17:23:43.965511Z","end":"2026-02-13T17:23:44.203059Z","steps":["trace[1481124223] 'agreement among raft nodes before linearized reading' (duration: 237.337931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:23:44.203661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.373055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-13T17:23:44.20386Z","caller":"traceutil/trace.go:171","msg":"trace[867825319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"238.60336ms","start":"2026-02-13T17:23:43.965245Z","end":"2026-02-13T17:23:44.203848Z","steps":["trace[867825319] 'agreement among raft nodes before linearized reading' (duration: 238.319593ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:23:44.313119Z","caller":"traceutil/trace.go:171","msg":"trace[1213866117] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"103.792664ms","start":"2026-02-13T17:23:44.209293Z","end":"2026-02-13T17:23:44.313086Z","steps":["trace[1213866117] 'process raft request' (duration: 103.714482ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:23:44.3136Z","caller":"traceutil/trace.go:171","msg":"trace[1253388384] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"102.858131ms","start":"2026-02-13T17:23:44.210721Z","end":"2026-02-13T17:23:44.313579Z","steps":["trace[1253388384] 'process raft request' (duration: 102.330499ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:23:44.314313Z","caller":"traceutil/trace.go:171","msg":"trace[1192052467] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"103.536428ms","start":"2026-02-13T17:23:44.210763Z","end":"2026-02-13T17:23:44.314299Z","steps":["trace[1192052467] 'process raft request' (duration: 102.742139ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T17:23:44.315423Z","caller":"traceutil/trace.go:171","msg":"trace[872503546] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"104.459099ms","start":"2026-02-13T17:23:44.208924Z","end":"2026-02-13T17:23:44.313383Z","steps":["trace[872503546] 'process raft request' (duration: 99.148326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T17:23:44.315879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.831648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T17:23:44.316462Z","caller":"traceutil/trace.go:171","msg":"trace[491834119] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:972; }","duration":"105.485553ms","start":"2026-02-13T17:23:44.210961Z","end":"2026-02-13T17:23:44.316446Z","steps":["trace[491834119] 'agreement among raft nodes before linearized reading' (duration: 104.627473ms)"],"step_count":1}