{"level":"warn","ts":"2026-02-07T04:08:03.579608Z","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-07T04:08:03.579688Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-07T04:08:03.57976Z","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-07T04:08:03.579767Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-07T04:08:03.579783Z","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-07T04:08:03.580375Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-07T04:08:03.580436Z","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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-07T04:08:03.58698Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.350067ms"} {"level":"info","ts":"2026-02-07T04:08:03.615171Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-07T04:08:03.615301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T04:08:03.61534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-07T04:08:03.615382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T04:08:03.615403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-07T04:08:03.615472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-07T04:08:03.626793Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T04:08:03.630994Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T04:08:03.642342Z","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-07T04:08:03.65555Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T04:08:03.656556Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T04:08:03.656991Z","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-07T04:08:03.657101Z","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-07T04:08:03.657181Z","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-07T04:08:03.670645Z","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-07T04:08:03.67108Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T04:08:03.671123Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T04:08:03.671693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-07T04:08:03.671933Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-07T04:08:03.671862Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-07T04:08:03.672039Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-07T04:08:04.217121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T04:08:04.217207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T04:08:04.217222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-07T04:08:04.217233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-07T04:08:04.217239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-07T04:08:04.217252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-07T04:08:04.217259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-07T04:08:04.218915Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T04:08:04.219072Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:08:04.219175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:08:04.219425Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:08:04.219751Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T04:08:04.219785Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T04:08:04.219802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:08:04.21984Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:08:04.219862Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:08:04.229432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-07T04:08:04.235938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-07T04:08:13.912283Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132581056815558,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:08:13.948465Z","caller":"traceutil/trace.go:171","msg":"trace[1531343395] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:306; }","duration":"536.511446ms","start":"2026-02-07T04:08:13.411929Z","end":"2026-02-07T04:08:13.948441Z","steps":["trace[1531343395] 'read index received' (duration: 536.332201ms)","trace[1531343395] 'applied index is now lower than readState.Index' (duration: 178.095µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:13.948761Z","caller":"traceutil/trace.go:171","msg":"trace[1383915343] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"618.447783ms","start":"2026-02-07T04:08:13.330302Z","end":"2026-02-07T04:08:13.948749Z","steps":["trace[1383915343] 'process raft request' (duration: 618.018231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:13.949236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:13.330283Z","time spent":"618.511715ms","remote":"127.0.0.1:33668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:08:13.949576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.62664ms","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-07T04:08:13.949662Z","caller":"traceutil/trace.go:171","msg":"trace[719760192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:298; }","duration":"537.749943ms","start":"2026-02-07T04:08:13.411893Z","end":"2026-02-07T04:08:13.949643Z","steps":["trace[719760192] 'agreement among raft nodes before linearized reading' (duration: 537.625479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:13.949696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:13.411876Z","time spent":"537.811235ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-07T04:08:17.078568Z","caller":"traceutil/trace.go:171","msg":"trace[1789484860] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"116.187938ms","start":"2026-02-07T04:08:16.962364Z","end":"2026-02-07T04:08:17.078551Z","steps":["trace[1789484860] 'process raft request' (duration: 116.086355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:17.470527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.321138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T04:08:17.470588Z","caller":"traceutil/trace.go:171","msg":"trace[120688214] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:359; }","duration":"104.409291ms","start":"2026-02-07T04:08:17.366165Z","end":"2026-02-07T04:08:17.470575Z","steps":["trace[120688214] 'range keys from in-memory index tree' (duration: 104.260596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:17.470857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.302394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-54p5f\" ","response":"range_response_count:1 size:4417"} {"level":"info","ts":"2026-02-07T04:08:17.470883Z","caller":"traceutil/trace.go:171","msg":"trace[1939042495] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-54p5f; range_end:; response_count:1; response_revision:359; }","duration":"111.355896ms","start":"2026-02-07T04:08:17.359521Z","end":"2026-02-07T04:08:17.470877Z","steps":["trace[1939042495] 'range keys from in-memory index tree' (duration: 111.234183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:17.470985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.256553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:17.471002Z","caller":"traceutil/trace.go:171","msg":"trace[589250223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:359; }","duration":"110.289355ms","start":"2026-02-07T04:08:17.360707Z","end":"2026-02-07T04:08:17.470997Z","steps":["trace[589250223] 'range keys from in-memory index tree' (duration: 110.208802ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:17.64199Z","caller":"traceutil/trace.go:171","msg":"trace[451635286] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"117.529118ms","start":"2026-02-07T04:08:17.524445Z","end":"2026-02-07T04:08:17.641974Z","steps":["trace[451635286] 'read index received' (duration: 13.762806ms)","trace[451635286] 'applied index is now lower than readState.Index' (duration: 103.765582ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:17.642071Z","caller":"traceutil/trace.go:171","msg":"trace[1402127980] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"120.242659ms","start":"2026-02-07T04:08:17.52182Z","end":"2026-02-07T04:08:17.642062Z","steps":["trace[1402127980] 'process raft request' (duration: 63.448453ms)","trace[1402127980] 'compare' (duration: 56.626491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:17.64226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.803406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-07T04:08:17.642282Z","caller":"traceutil/trace.go:171","msg":"trace[1379829964] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:362; }","duration":"117.854858ms","start":"2026-02-07T04:08:17.524421Z","end":"2026-02-07T04:08:17.642276Z","steps":["trace[1379829964] 'agreement among raft nodes before linearized reading' (duration: 117.788016ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:18.937438Z","caller":"traceutil/trace.go:171","msg":"trace[1170214803] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"363.2489ms","start":"2026-02-07T04:08:18.574152Z","end":"2026-02-07T04:08:18.937401Z","steps":["trace[1170214803] 'process raft request' (duration: 363.118796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:18.937733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:18.574141Z","time spent":"363.358564ms","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6879,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:19.528552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.04553ms","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-02-07T04:08:19.528651Z","caller":"traceutil/trace.go:171","msg":"trace[1392978451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:368; }","duration":"435.187605ms","start":"2026-02-07T04:08:19.093445Z","end":"2026-02-07T04:08:19.528633Z","steps":["trace[1392978451] 'range keys from in-memory index tree' (duration: 434.928867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:19.528704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:19.093417Z","time spent":"435.274187ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:08:19.528693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.548736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-07T04:08:19.52876Z","caller":"traceutil/trace.go:171","msg":"trace[812303084] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:368; }","duration":"166.646069ms","start":"2026-02-07T04:08:19.362097Z","end":"2026-02-07T04:08:19.528743Z","steps":["trace[812303084] 'range keys from in-memory index tree' (duration: 166.495474ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:19.929639Z","caller":"traceutil/trace.go:171","msg":"trace[855656186] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"433.367961ms","start":"2026-02-07T04:08:19.496251Z","end":"2026-02-07T04:08:19.929619Z","steps":["trace[855656186] 'process raft request' (duration: 433.245127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:19.929914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:19.496228Z","time spent":"433.627649ms","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:08:19.935607Z","caller":"traceutil/trace.go:171","msg":"trace[288467761] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"296.158591ms","start":"2026-02-07T04:08:19.639436Z","end":"2026-02-07T04:08:19.935595Z","steps":["trace[288467761] 'read index received' (duration: 290.253517ms)","trace[288467761] 'applied index is now lower than readState.Index' (duration: 5.904024ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:19.9358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.353887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4055"} {"level":"info","ts":"2026-02-07T04:08:19.935885Z","caller":"traceutil/trace.go:171","msg":"trace[1510679407] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:372; }","duration":"296.47109ms","start":"2026-02-07T04:08:19.639404Z","end":"2026-02-07T04:08:19.935875Z","steps":["trace[1510679407] 'agreement among raft nodes before linearized reading' (duration: 296.356537ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:19.93606Z","caller":"traceutil/trace.go:171","msg":"trace[1680265335] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"394.670689ms","start":"2026-02-07T04:08:19.541381Z","end":"2026-02-07T04:08:19.936052Z","steps":["trace[1680265335] 'process raft request' (duration: 394.162404ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:19.936181Z","caller":"traceutil/trace.go:171","msg":"trace[1622754840] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"402.425078ms","start":"2026-02-07T04:08:19.5337Z","end":"2026-02-07T04:08:19.936125Z","steps":["trace[1622754840] 'process raft request' (duration: 401.782669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:19.936234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:19.541363Z","time spent":"394.815483ms","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6669,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:19.936393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:19.533679Z","time spent":"402.590682ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:08:19.936588Z","caller":"traceutil/trace.go:171","msg":"trace[1474381806] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"264.863977ms","start":"2026-02-07T04:08:19.671714Z","end":"2026-02-07T04:08:19.936578Z","steps":["trace[1474381806] 'process raft request' (duration: 263.858617ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:32.914289Z","caller":"traceutil/trace.go:171","msg":"trace[1571916740] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"103.994953ms","start":"2026-02-07T04:08:32.810273Z","end":"2026-02-07T04:08:32.914267Z","steps":["trace[1571916740] 'process raft request' (duration: 39.080845ms)","trace[1571916740] 'compare' (duration: 64.216587ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:36.494514Z","caller":"traceutil/trace.go:171","msg":"trace[1391337078] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"243.674461ms","start":"2026-02-07T04:08:36.250823Z","end":"2026-02-07T04:08:36.494498Z","steps":["trace[1391337078] 'read index received' (duration: 243.542657ms)","trace[1391337078] 'applied index is now lower than readState.Index' (duration: 131.094µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:36.494729Z","caller":"traceutil/trace.go:171","msg":"trace[1814300468] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"304.500758ms","start":"2026-02-07T04:08:36.190221Z","end":"2026-02-07T04:08:36.494722Z","steps":["trace[1814300468] 'process raft request' (duration: 304.188789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:36.494818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:36.190209Z","time spent":"304.55922ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:36.494955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.131214ms","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-07T04:08:36.494977Z","caller":"traceutil/trace.go:171","msg":"trace[399183893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"244.221757ms","start":"2026-02-07T04:08:36.250747Z","end":"2026-02-07T04:08:36.494969Z","steps":["trace[399183893] 'agreement among raft nodes before linearized reading' (duration: 244.184736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:36.495863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.20087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:08:36.49591Z","caller":"traceutil/trace.go:171","msg":"trace[438053974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"125.260381ms","start":"2026-02-07T04:08:36.370626Z","end":"2026-02-07T04:08:36.495886Z","steps":["trace[438053974] 'agreement among raft nodes before linearized reading' (duration: 124.484548ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:37.975671Z","caller":"traceutil/trace.go:171","msg":"trace[551941838] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"136.03795ms","start":"2026-02-07T04:08:37.839615Z","end":"2026-02-07T04:08:37.975653Z","steps":["trace[551941838] 'process raft request' (duration: 135.953048ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:38.040893Z","caller":"traceutil/trace.go:171","msg":"trace[83885587] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"200.868947ms","start":"2026-02-07T04:08:37.839995Z","end":"2026-02-07T04:08:38.040864Z","steps":["trace[83885587] 'process raft request' (duration: 200.707512ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:38.040899Z","caller":"traceutil/trace.go:171","msg":"trace[1759100304] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"194.340394ms","start":"2026-02-07T04:08:37.846534Z","end":"2026-02-07T04:08:38.040874Z","steps":["trace[1759100304] 'process raft request' (duration: 194.277962ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:38.44318Z","caller":"traceutil/trace.go:171","msg":"trace[1354972295] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"174.299941ms","start":"2026-02-07T04:08:38.268856Z","end":"2026-02-07T04:08:38.443156Z","steps":["trace[1354972295] 'read index received' (duration: 126.268782ms)","trace[1354972295] 'applied index is now lower than readState.Index' (duration: 48.030008ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:38.44349Z","caller":"traceutil/trace.go:171","msg":"trace[1482171415] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"186.252874ms","start":"2026-02-07T04:08:38.257027Z","end":"2026-02-07T04:08:38.44328Z","steps":["trace[1482171415] 'process raft request' (duration: 138.126282ms)","trace[1482171415] 'compare' (duration: 47.873165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:38.444055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.158386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:38.444198Z","caller":"traceutil/trace.go:171","msg":"trace[682311231] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:495; }","duration":"175.346192ms","start":"2026-02-07T04:08:38.268833Z","end":"2026-02-07T04:08:38.444179Z","steps":["trace[682311231] 'agreement among raft nodes before linearized reading' (duration: 175.145966ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:38.740115Z","caller":"traceutil/trace.go:171","msg":"trace[1037480953] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"471.235067ms","start":"2026-02-07T04:08:38.268858Z","end":"2026-02-07T04:08:38.740093Z","steps":["trace[1037480953] 'process raft request' (duration: 471.028761ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:38.740139Z","caller":"traceutil/trace.go:171","msg":"trace[742590659] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:513; }","duration":"296.872515ms","start":"2026-02-07T04:08:38.443249Z","end":"2026-02-07T04:08:38.740122Z","steps":["trace[742590659] 'read index received' (duration: 296.865915ms)","trace[742590659] 'applied index is now lower than readState.Index' (duration: 5.38µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:38.740208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.268847Z","time spent":"471.30664ms","remote":"127.0.0.1:33552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:08:38.740258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.16115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:38.740287Z","caller":"traceutil/trace.go:171","msg":"trace[992469435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"349.222382ms","start":"2026-02-07T04:08:38.391057Z","end":"2026-02-07T04:08:38.740279Z","steps":["trace[992469435] 'agreement among raft nodes before linearized reading' (duration: 349.14769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.740314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.391044Z","time spent":"349.264313ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T04:08:38.740059Z","caller":"traceutil/trace.go:171","msg":"trace[143883896] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"469.847107ms","start":"2026-02-07T04:08:38.270191Z","end":"2026-02-07T04:08:38.740038Z","steps":["trace[143883896] 'process raft request' (duration: 469.789655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.740615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.270181Z","time spent":"470.392013ms","remote":"127.0.0.1:33932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3328,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:08:38.864334Z","caller":"traceutil/trace.go:171","msg":"trace[2060665343] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"417.396976ms","start":"2026-02-07T04:08:38.446917Z","end":"2026-02-07T04:08:38.864314Z","steps":["trace[2060665343] 'process raft request' (duration: 417.250582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.864468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.446901Z","time spent":"417.5072ms","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2550,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:08:38.865181Z","caller":"traceutil/trace.go:171","msg":"trace[1986623328] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:514; }","duration":"124.981984ms","start":"2026-02-07T04:08:38.740187Z","end":"2026-02-07T04:08:38.865169Z","steps":["trace[1986623328] 'read index received' (duration: 124.975473ms)","trace[1986623328] 'applied index is now lower than readState.Index' (duration: 5.331µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:38.865425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.297267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:08:38.865465Z","caller":"traceutil/trace.go:171","msg":"trace[78651463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"364.350278ms","start":"2026-02-07T04:08:38.501105Z","end":"2026-02-07T04:08:38.865455Z","steps":["trace[78651463] 'agreement among raft nodes before linearized reading' (duration: 364.128652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.865499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.5011Z","time spent":"364.392039ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:08:38.865737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.777169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-07T04:08:38.865953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.860821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-07T04:08:38.865998Z","caller":"traceutil/trace.go:171","msg":"trace[345564469] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:498; }","duration":"372.932242ms","start":"2026-02-07T04:08:38.493054Z","end":"2026-02-07T04:08:38.865986Z","steps":["trace[345564469] 'agreement among raft nodes before linearized reading' (duration: 372.850789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.866069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.493037Z","time spent":"373.022365ms","remote":"127.0.0.1:33570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12749,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-07T04:08:38.866175Z","caller":"traceutil/trace.go:171","msg":"trace[41379154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"369.065958ms","start":"2026-02-07T04:08:38.496902Z","end":"2026-02-07T04:08:38.865968Z","steps":["trace[41379154] 'agreement among raft nodes before linearized reading' (duration: 368.520412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.866631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.518783ms","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-07T04:08:38.866673Z","caller":"traceutil/trace.go:171","msg":"trace[1620678551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"366.569314ms","start":"2026-02-07T04:08:38.500094Z","end":"2026-02-07T04:08:38.866663Z","steps":["trace[1620678551] 'agreement among raft nodes before linearized reading' (duration: 366.457101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.866705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.500089Z","time spent":"366.610345ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:08:38.867342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.496896Z","time spent":"369.365587ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:08:38.889119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.999934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:38.889179Z","caller":"traceutil/trace.go:171","msg":"trace[1343747431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"147.097327ms","start":"2026-02-07T04:08:38.742071Z","end":"2026-02-07T04:08:38.889168Z","steps":["trace[1343747431] 'agreement among raft nodes before linearized reading' (duration: 146.934522ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:38.889139Z","caller":"traceutil/trace.go:171","msg":"trace[970405311] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"141.143691ms","start":"2026-02-07T04:08:38.747979Z","end":"2026-02-07T04:08:38.889123Z","steps":["trace[970405311] 'process raft request' (duration: 140.948605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.889325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.482701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3343"} {"level":"info","ts":"2026-02-07T04:08:38.889346Z","caller":"traceutil/trace.go:171","msg":"trace[496586932] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:499; }","duration":"143.528561ms","start":"2026-02-07T04:08:38.745812Z","end":"2026-02-07T04:08:38.889341Z","steps":["trace[496586932] 'agreement among raft nodes before linearized reading' (duration: 143.49117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:40.672982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132581056815993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:08:41.173495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132581056815993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:08:41.28205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.109862489s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-07T04:08:41.342497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327611267s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:08:41.343213Z","caller":"traceutil/trace.go:171","msg":"trace[1180936014] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.521168268s","start":"2026-02-07T04:08:39.821752Z","end":"2026-02-07T04:08:41.34292Z","steps":["trace[1180936014] 'process raft request' (duration: 192.949603ms)","trace[1180936014] 'compare' (duration: 1.326832044s)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:41.343637Z","caller":"traceutil/trace.go:171","msg":"trace[964648690] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.458173276s","start":"2026-02-07T04:08:39.885451Z","end":"2026-02-07T04:08:41.343624Z","steps":["trace[964648690] 'process raft request' (duration: 1.457192337s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.34369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:39.885427Z","time spent":"1.458237288s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:08:41.343436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:39.821726Z","time spent":"1.521671463s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:08:41.379437Z","caller":"traceutil/trace.go:171","msg":"trace[752429132] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:546; }","duration":"1.206807465s","start":"2026-02-07T04:08:40.172615Z","end":"2026-02-07T04:08:41.379423Z","steps":["trace[752429132] 'read index received' (duration: 1.109635872s)","trace[752429132] 'applied index is now lower than readState.Index' (duration: 97.171023ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:41.37981Z","caller":"traceutil/trace.go:171","msg":"trace[1601560371] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.294967281s","start":"2026-02-07T04:08:40.084833Z","end":"2026-02-07T04:08:41.3798Z","steps":["trace[1601560371] 'process raft request' (duration: 1.294335473s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.3801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.084809Z","time spent":"1.295088235s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:08:41.38043Z","caller":"traceutil/trace.go:171","msg":"trace[15745992] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.207711002s","start":"2026-02-07T04:08:40.172691Z","end":"2026-02-07T04:08:41.380402Z","steps":["trace[15745992] 'process raft request' (duration: 1.206553098s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.380561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.172681Z","time spent":"1.207848536s","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4407,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:08:41.380788Z","caller":"traceutil/trace.go:171","msg":"trace[185013849] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.094241948s","start":"2026-02-07T04:08:40.286485Z","end":"2026-02-07T04:08:41.380727Z","steps":["trace[185013849] 'process raft request' (duration: 1.092798405s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.380884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.286469Z","time spent":"1.094396453s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:08:41.381271Z","caller":"traceutil/trace.go:171","msg":"trace[285371221] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"895.273436ms","start":"2026-02-07T04:08:40.485982Z","end":"2026-02-07T04:08:41.381256Z","steps":["trace[285371221] 'process raft request' (duration: 893.342589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.381502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.485961Z","time spent":"895.439561ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:08:41.382412Z","caller":"traceutil/trace.go:171","msg":"trace[1388492320] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"895.359529ms","start":"2026-02-07T04:08:40.487043Z","end":"2026-02-07T04:08:41.382402Z","steps":["trace[1388492320] 'process raft request' (duration: 892.314879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.382526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.48702Z","time spent":"895.485953ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:08:41.383126Z","caller":"traceutil/trace.go:171","msg":"trace[1224067294] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"697.388047ms","start":"2026-02-07T04:08:40.685728Z","end":"2026-02-07T04:08:41.383116Z","steps":["trace[1224067294] 'process raft request' (duration: 693.673637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.395365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.685705Z","time spent":"709.609628ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:08:41.383524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.608724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:41.395476Z","caller":"traceutil/trace.go:171","msg":"trace[15151503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"1.002606159s","start":"2026-02-07T04:08:40.392861Z","end":"2026-02-07T04:08:41.395468Z","steps":["trace[15151503] 'agreement among raft nodes before linearized reading' (duration: 990.608525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.3955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.392842Z","time spent":"1.00265045s","remote":"127.0.0.1:33472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:08:41.383888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.084228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-07T04:08:41.395569Z","caller":"traceutil/trace.go:171","msg":"trace[190250768] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:538; }","duration":"113.789614ms","start":"2026-02-07T04:08:41.281773Z","end":"2026-02-07T04:08:41.395562Z","steps":["trace[190250768] 'agreement among raft nodes before linearized reading' (duration: 101.947184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.383952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.601895ms","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-02-07T04:08:41.395677Z","caller":"traceutil/trace.go:171","msg":"trace[93326903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:538; }","duration":"225.355652ms","start":"2026-02-07T04:08:41.170316Z","end":"2026-02-07T04:08:41.395672Z","steps":["trace[93326903] 'agreement among raft nodes before linearized reading' (duration: 213.589145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.383975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.833414ms","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-07T04:08:41.395754Z","caller":"traceutil/trace.go:171","msg":"trace[670561960] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:538; }","duration":"313.638012ms","start":"2026-02-07T04:08:41.082112Z","end":"2026-02-07T04:08:41.39575Z","steps":["trace[670561960] 'agreement among raft nodes before linearized reading' (duration: 301.852753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.395773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:41.082098Z","time spent":"313.668503ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-07T04:08:41.383999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.345069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:08:41.395847Z","caller":"traceutil/trace.go:171","msg":"trace[636570394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"471.21721ms","start":"2026-02-07T04:08:40.924625Z","end":"2026-02-07T04:08:41.395843Z","steps":["trace[636570394] 'agreement among raft nodes before linearized reading' (duration: 459.35836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.395861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.924615Z","time spent":"471.241571ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:08:41.38426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.588797ms","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-07T04:08:41.39594Z","caller":"traceutil/trace.go:171","msg":"trace[1064863764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"472.309363ms","start":"2026-02-07T04:08:40.923627Z","end":"2026-02-07T04:08:41.395936Z","steps":["trace[1064863764] 'agreement among raft nodes before linearized reading' (duration: 460.423932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.395956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.923594Z","time spent":"472.356975ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:08:41.3846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.803892ms","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-07T04:08:41.396061Z","caller":"traceutil/trace.go:171","msg":"trace[2026649819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:538; }","duration":"474.314562ms","start":"2026-02-07T04:08:40.921742Z","end":"2026-02-07T04:08:41.396056Z","steps":["trace[2026649819] 'agreement among raft nodes before linearized reading' (duration: 462.533703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.396079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.92172Z","time spent":"474.354483ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:08:41.384628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.443154ms","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-07T04:08:41.396169Z","caller":"traceutil/trace.go:171","msg":"trace[1685241182] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:538; }","duration":"514.019176ms","start":"2026-02-07T04:08:40.882145Z","end":"2026-02-07T04:08:41.396164Z","steps":["trace[1685241182] 'agreement among raft nodes before linearized reading' (duration: 502.469615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.396185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.882126Z","time spent":"514.054107ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-07T04:08:41.388399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215784181s","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-07T04:08:41.396248Z","caller":"traceutil/trace.go:171","msg":"trace[2045981067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"1.223665473s","start":"2026-02-07T04:08:40.172578Z","end":"2026-02-07T04:08:41.396243Z","steps":["trace[2045981067] 'agreement among raft nodes before linearized reading' (duration: 1.215796761s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.396264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:40.172541Z","time spent":"1.223718236s","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-07T04:08:41.840796Z","caller":"traceutil/trace.go:171","msg":"trace[800543200] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"353.251323ms","start":"2026-02-07T04:08:41.48753Z","end":"2026-02-07T04:08:41.840782Z","steps":["trace[800543200] 'process raft request' (duration: 343.829635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:41.840878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:41.487514Z","time spent":"353.318234ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5162,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:42.600266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.006423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:08:42.600354Z","caller":"traceutil/trace.go:171","msg":"trace[407482288] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"1.106943345s","start":"2026-02-07T04:08:41.493396Z","end":"2026-02-07T04:08:42.60034Z","steps":["trace[407482288] 'read index received' (duration: 337.943261ms)","trace[407482288] 'applied index is now lower than readState.Index' (duration: 768.999084ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:42.600543Z","caller":"traceutil/trace.go:171","msg":"trace[681554172] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.109401598s","start":"2026-02-07T04:08:41.49113Z","end":"2026-02-07T04:08:42.600532Z","steps":["trace[681554172] 'process raft request' (duration: 495.057375ms)","trace[681554172] 'compare' (duration: 613.893189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:42.6006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:41.491124Z","time spent":"1.109446089s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:42.600791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107389608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-07T04:08:42.600826Z","caller":"traceutil/trace.go:171","msg":"trace[1450377711] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:548; }","duration":"1.107434339s","start":"2026-02-07T04:08:41.493378Z","end":"2026-02-07T04:08:42.600813Z","steps":["trace[1450377711] 'agreement among raft nodes before linearized reading' (duration: 1.107376977s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:42.600856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:41.493372Z","time spent":"1.10747498s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-02-07T04:08:42.91351Z","caller":"traceutil/trace.go:171","msg":"trace[134861375] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.066691665s","start":"2026-02-07T04:08:41.846802Z","end":"2026-02-07T04:08:42.913494Z","steps":["trace[134861375] 'process raft request' (duration: 1.064587973s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:42.913596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:41.846784Z","time spent":"1.066764588s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:08:42.919111Z","caller":"traceutil/trace.go:171","msg":"trace[1574354115] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"806.03043ms","start":"2026-02-07T04:08:42.113062Z","end":"2026-02-07T04:08:42.919092Z","steps":["trace[1574354115] 'process raft request' (duration: 805.00886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:42.919205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:42.113045Z","time spent":"806.119672ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:08:42.919628Z","caller":"traceutil/trace.go:171","msg":"trace[791118691] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"520.620681ms","start":"2026-02-07T04:08:42.399001Z","end":"2026-02-07T04:08:42.919622Z","steps":["trace[791118691] 'process raft request' (duration: 519.167718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:42.919661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:42.398985Z","time spent":"520.661223ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T04:08:42.919742Z","caller":"traceutil/trace.go:171","msg":"trace[792432877] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"292.883199ms","start":"2026-02-07T04:08:42.626852Z","end":"2026-02-07T04:08:42.919735Z","steps":["trace[792432877] 'process raft request' (duration: 291.436646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:42.93097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.20805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-07T04:08:42.931001Z","caller":"traceutil/trace.go:171","msg":"trace[45387984] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"448.280903ms","start":"2026-02-07T04:08:42.482713Z","end":"2026-02-07T04:08:42.930994Z","steps":["trace[45387984] 'agreement among raft nodes before linearized reading' (duration: 448.166809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:42.931025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:42.482694Z","time spent":"448.327574ms","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-07T04:08:42.931148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049493657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-07T04:08:42.931167Z","caller":"traceutil/trace.go:171","msg":"trace[1649369762] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"1.049535559s","start":"2026-02-07T04:08:41.881626Z","end":"2026-02-07T04:08:42.931162Z","steps":["trace[1649369762] 'agreement among raft nodes before linearized reading' (duration: 1.049487497s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:42.93118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:41.881614Z","time spent":"1.049562969s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-07T04:08:42.931281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249314434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-07T04:08:42.931308Z","caller":"traceutil/trace.go:171","msg":"trace[508458223] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"1.249363396s","start":"2026-02-07T04:08:41.68194Z","end":"2026-02-07T04:08:42.931304Z","steps":["trace[508458223] 'agreement among raft nodes before linearized reading' (duration: 1.249307814s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:42.931325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:41.681928Z","time spent":"1.249394406s","remote":"127.0.0.1:33532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-02-07T04:08:42.931383Z","caller":"traceutil/trace.go:171","msg":"trace[2025695437] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:565; }","duration":"330.45839ms","start":"2026-02-07T04:08:42.600378Z","end":"2026-02-07T04:08:42.930837Z","steps":["trace[2025695437] 'read index received' (duration: 311.026345ms)","trace[2025695437] 'applied index is now lower than readState.Index' (duration: 19.430935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:42.931441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.428834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-07T04:08:42.931456Z","caller":"traceutil/trace.go:171","msg":"trace[1708375992] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"249.444564ms","start":"2026-02-07T04:08:42.682008Z","end":"2026-02-07T04:08:42.931452Z","steps":["trace[1708375992] 'agreement among raft nodes before linearized reading' (duration: 249.417383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:50.03681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132581056816134,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:08:50.574623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.81204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:08:50.57475Z","caller":"traceutil/trace.go:171","msg":"trace[809522607] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"1.038877357s","start":"2026-02-07T04:08:49.535858Z","end":"2026-02-07T04:08:50.574735Z","steps":["trace[809522607] 'read index received' (duration: 649.82221ms)","trace[809522607] 'applied index is now lower than readState.Index' (duration: 389.053887ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:50.574868Z","caller":"traceutil/trace.go:171","msg":"trace[1335782911] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.039260598s","start":"2026-02-07T04:08:49.535583Z","end":"2026-02-07T04:08:50.574844Z","steps":["trace[1335782911] 'process raft request' (duration: 650.133529ms)","trace[1335782911] 'compare' (duration: 387.848892ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:50.574979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:49.535545Z","time spent":"1.039373642s","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:50.575008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039142655s","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-07T04:08:50.575073Z","caller":"traceutil/trace.go:171","msg":"trace[1971194874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"1.039260069s","start":"2026-02-07T04:08:49.535802Z","end":"2026-02-07T04:08:50.575062Z","steps":["trace[1971194874] 'agreement among raft nodes before linearized reading' (duration: 1.039076053s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:50.57514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:49.535793Z","time spent":"1.039335131s","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:08:50.575263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.344789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:08:50.575287Z","caller":"traceutil/trace.go:171","msg":"trace[847227878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:581; }","duration":"975.40501ms","start":"2026-02-07T04:08:49.599875Z","end":"2026-02-07T04:08:50.57528Z","steps":["trace[847227878] 'agreement among raft nodes before linearized reading' (duration: 975.359059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:50.575305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:49.599856Z","time spent":"975.446251ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:08:50.575354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.5681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:50.575366Z","caller":"traceutil/trace.go:171","msg":"trace[1382417908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"181.601311ms","start":"2026-02-07T04:08:50.39376Z","end":"2026-02-07T04:08:50.575362Z","steps":["trace[1382417908] 'agreement among raft nodes before linearized reading' (duration: 181.58271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:50.575458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.567016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:08:50.575474Z","caller":"traceutil/trace.go:171","msg":"trace[1673739147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"948.602427ms","start":"2026-02-07T04:08:49.626867Z","end":"2026-02-07T04:08:50.575469Z","steps":["trace[1673739147] 'agreement among raft nodes before linearized reading' (duration: 948.573566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:50.575486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:49.626857Z","time spent":"948.626068ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:08:50.57555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.859714ms","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-02-07T04:08:50.575562Z","caller":"traceutil/trace.go:171","msg":"trace[678399342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"949.909996ms","start":"2026-02-07T04:08:49.625648Z","end":"2026-02-07T04:08:50.575558Z","steps":["trace[678399342] 'agreement among raft nodes before linearized reading' (duration: 949.886825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:50.575575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:49.625627Z","time spent":"949.945077ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:08:52.23171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.236426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:52.231766Z","caller":"traceutil/trace.go:171","msg":"trace[804592665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"837.318518ms","start":"2026-02-07T04:08:51.394433Z","end":"2026-02-07T04:08:52.231752Z","steps":["trace[804592665] 'range keys from in-memory index tree' (duration: 837.182644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:52.231791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:51.394418Z","time spent":"837.36697ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T04:08:52.305941Z","caller":"traceutil/trace.go:171","msg":"trace[1654170399] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"771.712448ms","start":"2026-02-07T04:08:51.53421Z","end":"2026-02-07T04:08:52.305922Z","steps":["trace[1654170399] 'process raft request' (duration: 771.293295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:52.306044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:51.534193Z","time spent":"771.79448ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:53.094176Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132581056816146,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:08:53.519272Z","caller":"traceutil/trace.go:171","msg":"trace[35592025] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"937.059649ms","start":"2026-02-07T04:08:52.582197Z","end":"2026-02-07T04:08:53.519256Z","steps":["trace[35592025] 'process raft request' (duration: 936.972117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:53.519368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:52.582184Z","time spent":"937.132451ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:55.155162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.636019389s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-07T04:08:55.392502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000167043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-07T04:08:55.392562Z","caller":"traceutil/trace.go:171","msg":"trace[274720634] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000257786s","start":"2026-02-07T04:08:53.392289Z","end":"2026-02-07T04:08:55.392547Z","steps":["trace[274720634] 'agreement among raft nodes before linearized reading' (duration: 2.000181433s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.392598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:53.392273Z","time spent":"2.000317747s","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T04:08:55.661205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626764378s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:55.661366Z","caller":"traceutil/trace.go:171","msg":"trace[364988134] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:587; }","duration":"1.626930663s","start":"2026-02-07T04:08:54.034415Z","end":"2026-02-07T04:08:55.661346Z","steps":["trace[364988134] 'range keys from in-memory index tree' (duration: 1.626749207s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.680333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.025344ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-07T04:08:55.680654Z","caller":"traceutil/trace.go:171","msg":"trace[1938429196] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"3.087009435s","start":"2026-02-07T04:08:52.593629Z","end":"2026-02-07T04:08:55.680638Z","steps":["trace[1938429196] 'read index received' (duration: 926.030723ms)","trace[1938429196] 'applied index is now lower than readState.Index' (duration: 2.160976961s)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:55.68126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.025135983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:08:55.68191Z","caller":"traceutil/trace.go:171","msg":"trace[192355022] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:587; }","duration":"3.025811694s","start":"2026-02-07T04:08:52.656084Z","end":"2026-02-07T04:08:55.681895Z","steps":["trace[192355022] 'agreement among raft nodes before linearized reading' (duration: 3.025082162s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.681032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.087390976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-07T04:08:55.681658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.465148ms","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-07T04:08:55.682882Z","caller":"traceutil/trace.go:171","msg":"trace[434993034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"159.694605ms","start":"2026-02-07T04:08:55.52318Z","end":"2026-02-07T04:08:55.682875Z","steps":["trace[434993034] 'agreement among raft nodes before linearized reading' (duration: 158.377716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.681698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.050812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T04:08:55.681765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.02467219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-07T04:08:55.681848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.025444483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-07T04:08:55.682731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:52.656066Z","time spent":"3.026646979s","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-07T04:08:55.683855Z","caller":"traceutil/trace.go:171","msg":"trace[2127131461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"3.026759422s","start":"2026-02-07T04:08:52.657076Z","end":"2026-02-07T04:08:55.683835Z","steps":["trace[2127131461] 'agreement among raft nodes before linearized reading' (duration: 3.024637089s)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:55.684444Z","caller":"traceutil/trace.go:171","msg":"trace[243877415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"3.02804962s","start":"2026-02-07T04:08:52.656379Z","end":"2026-02-07T04:08:55.684429Z","steps":["trace[243877415] 'agreement among raft nodes before linearized reading' (duration: 3.025400181s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.684808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:52.656374Z","time spent":"3.02838622s","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T04:08:55.682835Z","caller":"traceutil/trace.go:171","msg":"trace[1564641608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"3.089199119s","start":"2026-02-07T04:08:52.593624Z","end":"2026-02-07T04:08:55.682823Z","steps":["trace[1564641608] 'agreement among raft nodes before linearized reading' (duration: 3.087245501s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.685444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:52.593585Z","time spent":"3.091818216s","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:08:55.685634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:52.657069Z","time spent":"3.026845974s","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-07T04:08:55.685814Z","caller":"traceutil/trace.go:171","msg":"trace[721312260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"293.183734ms","start":"2026-02-07T04:08:55.392617Z","end":"2026-02-07T04:08:55.685801Z","steps":["trace[721312260] 'agreement among raft nodes before linearized reading' (duration: 289.064192ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:57.832263Z","caller":"traceutil/trace.go:171","msg":"trace[1240278255] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"129.516152ms","start":"2026-02-07T04:08:57.702724Z","end":"2026-02-07T04:08:57.83224Z","steps":["trace[1240278255] 'read index received' (duration: 129.322197ms)","trace[1240278255] 'applied index is now lower than readState.Index' (duration: 192.985µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:57.832382Z","caller":"traceutil/trace.go:171","msg":"trace[596349143] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"329.911431ms","start":"2026-02-07T04:08:57.502461Z","end":"2026-02-07T04:08:57.832372Z","steps":["trace[596349143] 'process raft request' (duration: 329.580501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:57.832493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.502433Z","time spent":"329.977103ms","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17152,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:57.832577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.764941ms","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-07T04:08:57.832638Z","caller":"traceutil/trace.go:171","msg":"trace[2010431593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"127.871114ms","start":"2026-02-07T04:08:57.704752Z","end":"2026-02-07T04:08:57.832624Z","steps":["trace[2010431593] 'agreement among raft nodes before linearized reading' (duration: 127.767681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:57.832817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.092179ms","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-07T04:08:57.832834Z","caller":"traceutil/trace.go:171","msg":"trace[323066403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"130.131521ms","start":"2026-02-07T04:08:57.702698Z","end":"2026-02-07T04:08:57.83283Z","steps":["trace[323066403] 'agreement among raft nodes before linearized reading' (duration: 130.10033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:57.832912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.790692ms","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-02-07T04:08:57.832924Z","caller":"traceutil/trace.go:171","msg":"trace[1312201802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"124.823683ms","start":"2026-02-07T04:08:57.708096Z","end":"2026-02-07T04:08:57.83292Z","steps":["trace[1312201802] 'agreement among raft nodes before linearized reading' (duration: 124.794263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:57.832917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.496784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-07T04:08:57.832954Z","caller":"traceutil/trace.go:171","msg":"trace[888979966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"124.554775ms","start":"2026-02-07T04:08:57.708388Z","end":"2026-02-07T04:08:57.832943Z","steps":["trace[888979966] 'agreement among raft nodes before linearized reading' (duration: 124.423801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:57.833127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.00222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:08:57.833194Z","caller":"traceutil/trace.go:171","msg":"trace[546844164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:601; }","duration":"121.090022ms","start":"2026-02-07T04:08:57.712092Z","end":"2026-02-07T04:08:57.833182Z","steps":["trace[546844164] 'agreement among raft nodes before linearized reading' (duration: 120.989689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.09363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.803537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:08:58.093751Z","caller":"traceutil/trace.go:171","msg":"trace[1683708780] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"254.123059ms","start":"2026-02-07T04:08:57.839593Z","end":"2026-02-07T04:08:58.093716Z","steps":["trace[1683708780] 'process raft request' (duration: 116.126766ms)","trace[1683708780] 'compare' (duration: 137.686484ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:58.141767Z","caller":"traceutil/trace.go:171","msg":"trace[2006778443] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"300.195442ms","start":"2026-02-07T04:08:57.841546Z","end":"2026-02-07T04:08:58.141741Z","steps":["trace[2006778443] 'process raft request' (duration: 300.055048ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:58.141769Z","caller":"traceutil/trace.go:171","msg":"trace[1906674144] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"292.157755ms","start":"2026-02-07T04:08:57.849593Z","end":"2026-02-07T04:08:58.14175Z","steps":["trace[1906674144] 'process raft request' (duration: 292.132044ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:58.14181Z","caller":"traceutil/trace.go:171","msg":"trace[1575176676] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"295.783572ms","start":"2026-02-07T04:08:57.846023Z","end":"2026-02-07T04:08:58.141807Z","steps":["trace[1575176676] 'process raft request' (duration: 295.669859ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:58.141839Z","caller":"traceutil/trace.go:171","msg":"trace[1909753681] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"292.616688ms","start":"2026-02-07T04:08:57.849219Z","end":"2026-02-07T04:08:58.141836Z","steps":["trace[1909753681] 'process raft request' (duration: 292.489944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.143309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.841536Z","time spent":"300.329576ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:08:58.846519Z","caller":"traceutil/trace.go:171","msg":"trace[768174407] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"160.990373ms","start":"2026-02-07T04:08:58.6855Z","end":"2026-02-07T04:08:58.846491Z","steps":["trace[768174407] 'process raft request' (duration: 123.111323ms)","trace[768174407] 'compare' (duration: 37.400136ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:09:36.518564Z","caller":"traceutil/trace.go:171","msg":"trace[1138530984] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"146.063958ms","start":"2026-02-07T04:09:36.372478Z","end":"2026-02-07T04:09:36.518541Z","steps":["trace[1138530984] 'process raft request' (duration: 145.939454ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:09:44.566662Z","caller":"traceutil/trace.go:171","msg":"trace[1677559626] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"153.427416ms","start":"2026-02-07T04:09:44.413222Z","end":"2026-02-07T04:09:44.566649Z","steps":["trace[1677559626] 'process raft request' (duration: 83.857895ms)","trace[1677559626] 'compare' (duration: 69.317474ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:09:46.79569Z","caller":"traceutil/trace.go:171","msg":"trace[713097030] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"149.213662ms","start":"2026-02-07T04:09:46.646453Z","end":"2026-02-07T04:09:46.795667Z","steps":["trace[713097030] 'read index received' (duration: 114.529004ms)","trace[713097030] 'applied index is now lower than readState.Index' (duration: 34.683768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:09:46.795833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.351426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:09:46.795965Z","caller":"traceutil/trace.go:171","msg":"trace[1308439170] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:844; }","duration":"149.516352ms","start":"2026-02-07T04:09:46.646433Z","end":"2026-02-07T04:09:46.79595Z","steps":["trace[1308439170] 'agreement among raft nodes before linearized reading' (duration: 149.322536ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:09:46.796447Z","caller":"traceutil/trace.go:171","msg":"trace[1592715449] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"178.16091ms","start":"2026-02-07T04:09:46.618272Z","end":"2026-02-07T04:09:46.796433Z","steps":["trace[1592715449] 'process raft request' (duration: 142.764141ms)","trace[1592715449] 'compare' (duration: 34.553554ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:10:15.304232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.558477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:10:15.30437Z","caller":"traceutil/trace.go:171","msg":"trace[1981503715] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"554.993965ms","start":"2026-02-07T04:10:14.749359Z","end":"2026-02-07T04:10:15.304353Z","steps":["trace[1981503715] 'read index received' (duration: 36.401µs)","trace[1981503715] 'applied index is now lower than readState.Index' (duration: 554.956504ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:10:15.304609Z","caller":"traceutil/trace.go:171","msg":"trace[1537020589] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"715.851146ms","start":"2026-02-07T04:10:14.588745Z","end":"2026-02-07T04:10:15.304596Z","steps":["trace[1537020589] 'process raft request' (duration: 57.839425ms)","trace[1537020589] 'compare' (duration: 656.915968ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:10:15.304675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:10:14.588705Z","time spent":"715.938858ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:10:15.304901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.563192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:10:15.304936Z","caller":"traceutil/trace.go:171","msg":"trace[456398693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"555.635144ms","start":"2026-02-07T04:10:14.749291Z","end":"2026-02-07T04:10:15.304926Z","steps":["trace[456398693] 'agreement among raft nodes before linearized reading' (duration: 555.526061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:10:15.304964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:10:14.749248Z","time spent":"555.709926ms","remote":"127.0.0.1:33746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:10:15.305138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.5153ms","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-07T04:10:15.305171Z","caller":"traceutil/trace.go:171","msg":"trace[1644585819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"241.582582ms","start":"2026-02-07T04:10:15.063581Z","end":"2026-02-07T04:10:15.305163Z","steps":["trace[1644585819] 'agreement among raft nodes before linearized reading' (duration: 241.50594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:10:15.305339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.667322ms","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-07T04:10:15.305373Z","caller":"traceutil/trace.go:171","msg":"trace[226816812] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:960; }","duration":"108.727794ms","start":"2026-02-07T04:10:15.196637Z","end":"2026-02-07T04:10:15.305365Z","steps":["trace[226816812] 'agreement among raft nodes before linearized reading' (duration: 108.683133ms)"],"step_count":1}