{"level":"warn","ts":"2026-02-05T17:37:02.217577Z","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-05T17:37:02.217817Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-05T17:37:02.217936Z","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-05T17:37:02.218002Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-05T17:37:02.218062Z","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-05T17:37:02.218686Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-02-05T17:37:02.218829Z","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.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-05T17:37:02.223963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.839476ms"} {"level":"info","ts":"2026-02-05T17:37:02.230826Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-02-05T17:37:02.231197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:37:02.231266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-02-05T17:37:02.23129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:37:02.231369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-02-05T17:37:02.231441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-02-05T17:37:02.255747Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:37:02.269993Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:37:02.272873Z","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-05T17:37:02.278353Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:37:02.288269Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:37:02.289437Z","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-05T17:37:02.289472Z","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-05T17:37:02.289479Z","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-05T17:37:02.28966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-02-05T17:37:02.290436Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-05T17:37:02.293081Z","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-05T17:37:02.29325Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:37:02.293272Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:37:02.293319Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-05T17:37:02.293334Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-05T17:37:03.033742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:37:03.033788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:37:03.033809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-02-05T17:37:03.033819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:37:03.033824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-05T17:37:03.033833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-02-05T17:37:03.03384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-05T17:37:03.041773Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:37:03.045841Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:37:03.046067Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:37:03.046341Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:37:03.046393Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:37:03.04643Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:37:03.046449Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:37:03.046469Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:37:03.046476Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:37:03.047828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-02-05T17:37:03.051468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:37:16.346494Z","caller":"traceutil/trace.go:171","msg":"trace[230486811] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"289.249235ms","start":"2026-02-05T17:37:16.057227Z","end":"2026-02-05T17:37:16.346476Z","steps":["trace[230486811] 'process raft request' (duration: 288.709131ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:18.685131Z","caller":"traceutil/trace.go:171","msg":"trace[2085090941] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"180.228185ms","start":"2026-02-05T17:37:18.504884Z","end":"2026-02-05T17:37:18.685113Z","steps":["trace[2085090941] 'process raft request' (duration: 179.794444ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:36.064031Z","caller":"traceutil/trace.go:171","msg":"trace[1023516908] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"100.605891ms","start":"2026-02-05T17:37:35.963393Z","end":"2026-02-05T17:37:36.063999Z","steps":["trace[1023516908] 'process raft request' (duration: 100.469078ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:36.254702Z","caller":"traceutil/trace.go:171","msg":"trace[431982383] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"129.422191ms","start":"2026-02-05T17:37:36.125261Z","end":"2026-02-05T17:37:36.254683Z","steps":["trace[431982383] 'process raft request' (duration: 129.302228ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:36.267121Z","caller":"traceutil/trace.go:171","msg":"trace[705408819] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"107.033358ms","start":"2026-02-05T17:37:36.160072Z","end":"2026-02-05T17:37:36.267105Z","steps":["trace[705408819] 'process raft request' (duration: 106.612257ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:36.595726Z","caller":"traceutil/trace.go:171","msg":"trace[1205693959] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"237.020964ms","start":"2026-02-05T17:37:36.358688Z","end":"2026-02-05T17:37:36.595709Z","steps":["trace[1205693959] 'process raft request' (duration: 236.918491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:37.454018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498573,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:37:37.954763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498573,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:37:38.034198Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.438753811s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:37:38.03442Z","caller":"traceutil/trace.go:171","msg":"trace[62868772] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.47865755s","start":"2026-02-05T17:37:36.555747Z","end":"2026-02-05T17:37:38.034404Z","steps":["trace[62868772] 'process raft request' (duration: 1.478573608s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.034758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:36.555729Z","time spent":"1.478742323s","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:37:38.152065Z","caller":"traceutil/trace.go:171","msg":"trace[2050016466] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"1.198797505s","start":"2026-02-05T17:37:36.953251Z","end":"2026-02-05T17:37:38.152048Z","steps":["trace[2050016466] 'read index received' (duration: 1.081635086s)","trace[2050016466] 'applied index is now lower than readState.Index' (duration: 117.161589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:38.152296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199006522s","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-05T17:37:38.152363Z","caller":"traceutil/trace.go:171","msg":"trace[644944325] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"1.199135065s","start":"2026-02-05T17:37:36.953211Z","end":"2026-02-05T17:37:38.152346Z","steps":["trace[644944325] 'agreement among raft nodes before linearized reading' (duration: 1.199012572s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.152416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:36.953194Z","time spent":"1.199197387s","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-05T17:37:38.152554Z","caller":"traceutil/trace.go:171","msg":"trace[1076720352] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.545715276s","start":"2026-02-05T17:37:36.606828Z","end":"2026-02-05T17:37:38.152543Z","steps":["trace[1076720352] 'process raft request' (duration: 1.545086569s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.152682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.061406ms","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-05T17:37:38.152713Z","caller":"traceutil/trace.go:171","msg":"trace[929693587] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:557; }","duration":"200.135208ms","start":"2026-02-05T17:37:37.952568Z","end":"2026-02-05T17:37:38.152703Z","steps":["trace[929693587] 'agreement among raft nodes before linearized reading' (duration: 200.058836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.152775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:36.606809Z","time spent":"1.545766008s","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:38.152801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.403546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:38.15282Z","caller":"traceutil/trace.go:171","msg":"trace[342697117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"391.438267ms","start":"2026-02-05T17:37:37.761376Z","end":"2026-02-05T17:37:38.152814Z","steps":["trace[342697117] 'agreement among raft nodes before linearized reading' (duration: 391.408166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.152837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.761369Z","time spent":"391.464008ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:37:38.15293Z","caller":"traceutil/trace.go:171","msg":"trace[2021748209] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.394805805s","start":"2026-02-05T17:37:36.758114Z","end":"2026-02-05T17:37:38.152919Z","steps":["trace[2021748209] 'process raft request' (duration: 1.393907641s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.152976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:36.758097Z","time spent":"1.394858107s","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:37:38.152991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.213006ms","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-05T17:37:38.15301Z","caller":"traceutil/trace.go:171","msg":"trace[892953657] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:557; }","duration":"400.265797ms","start":"2026-02-05T17:37:37.752739Z","end":"2026-02-05T17:37:38.153005Z","steps":["trace[892953657] 'agreement among raft nodes before linearized reading' (duration: 400.204976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.153024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.75272Z","time spent":"400.300488ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:38.153087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.19143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-05T17:37:38.153099Z","caller":"traceutil/trace.go:171","msg":"trace[567444550] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:557; }","duration":"600.239011ms","start":"2026-02-05T17:37:37.552856Z","end":"2026-02-05T17:37:38.153095Z","steps":["trace[567444550] 'agreement among raft nodes before linearized reading' (duration: 600.20093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.15311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.552839Z","time spent":"600.269101ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:38.153175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.680039ms","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-05T17:37:38.153187Z","caller":"traceutil/trace.go:171","msg":"trace[1532031719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"695.72319ms","start":"2026-02-05T17:37:37.457459Z","end":"2026-02-05T17:37:38.153182Z","steps":["trace[1532031719] 'agreement among raft nodes before linearized reading' (duration: 695.688809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.153199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.457441Z","time spent":"695.754291ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:37:38.153297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.705808ms","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-05T17:37:38.15331Z","caller":"traceutil/trace.go:171","msg":"trace[450817257] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:557; }","duration":"800.76078ms","start":"2026-02-05T17:37:37.352545Z","end":"2026-02-05T17:37:38.153306Z","steps":["trace[450817257] 'agreement among raft nodes before linearized reading' (duration: 800.716418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.153322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.352492Z","time spent":"800.826662ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:38.153378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.922686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T17:37:38.153449Z","caller":"traceutil/trace.go:171","msg":"trace[1721608149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:557; }","duration":"959.019978ms","start":"2026-02-05T17:37:37.194424Z","end":"2026-02-05T17:37:38.153444Z","steps":["trace[1721608149] 'agreement among raft nodes before linearized reading' (duration: 958.928016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.153464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.194409Z","time spent":"959.050259ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:38.15353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000793966s","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-05T17:37:38.153545Z","caller":"traceutil/trace.go:171","msg":"trace[195980054] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"1.000831447s","start":"2026-02-05T17:37:37.152707Z","end":"2026-02-05T17:37:38.153539Z","steps":["trace[195980054] 'agreement among raft nodes before linearized reading' (duration: 1.000810866s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.153556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.152695Z","time spent":"1.000858827s","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:38.153601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048072293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:37:38.153615Z","caller":"traceutil/trace.go:171","msg":"trace[1278915200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"1.048113964s","start":"2026-02-05T17:37:37.105495Z","end":"2026-02-05T17:37:38.153609Z","steps":["trace[1278915200] 'agreement among raft nodes before linearized reading' (duration: 1.048090283s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.153626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.105479Z","time spent":"1.048143984s","remote":"127.0.0.1:42302","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":"warn","ts":"2026-02-05T17:37:38.153728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127510805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:37:38.153743Z","caller":"traceutil/trace.go:171","msg":"trace[233024079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"1.127546036s","start":"2026-02-05T17:37:37.026192Z","end":"2026-02-05T17:37:38.153738Z","steps":["trace[233024079] 'agreement among raft nodes before linearized reading' (duration: 1.127510395s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.153754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:37.02618Z","time spent":"1.127571717s","remote":"127.0.0.1:42302","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-05T17:37:38.153817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186130271s","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-05T17:37:38.153828Z","caller":"traceutil/trace.go:171","msg":"trace[1635062333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"1.186164162s","start":"2026-02-05T17:37:36.96766Z","end":"2026-02-05T17:37:38.153824Z","steps":["trace[1635062333] 'agreement among raft nodes before linearized reading' (duration: 1.186142011s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:38.153839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:36.967648Z","time spent":"1.186188563s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:37:39.530447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.804662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:37:39.53051Z","caller":"traceutil/trace.go:171","msg":"trace[237824772] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"468.126574ms","start":"2026-02-05T17:37:39.062372Z","end":"2026-02-05T17:37:39.530498Z","steps":["trace[237824772] 'process raft request' (duration: 312.149798ms)","trace[237824772] 'compare' (duration: 155.687809ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:39.530538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:39.062359Z","time spent":"468.168715ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:37:39.535942Z","caller":"traceutil/trace.go:171","msg":"trace[117355889] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"383.234773ms","start":"2026-02-05T17:37:39.152692Z","end":"2026-02-05T17:37:39.535927Z","steps":["trace[117355889] 'read index received' (duration: 221.722136ms)","trace[117355889] 'applied index is now lower than readState.Index' (duration: 161.512027ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:39.536292Z","caller":"traceutil/trace.go:171","msg":"trace[560542925] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"458.479362ms","start":"2026-02-05T17:37:39.077804Z","end":"2026-02-05T17:37:39.536283Z","steps":["trace[560542925] 'process raft request' (duration: 457.944418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:39.536366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:39.077795Z","time spent":"458.517173ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:39.536517Z","caller":"traceutil/trace.go:171","msg":"trace[660511036] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"198.872094ms","start":"2026-02-05T17:37:39.337639Z","end":"2026-02-05T17:37:39.536511Z","steps":["trace[660511036] 'process raft request' (duration: 198.255317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:39.5367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.012234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-05T17:37:39.53673Z","caller":"traceutil/trace.go:171","msg":"trace[1525223266] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"384.071906ms","start":"2026-02-05T17:37:39.15265Z","end":"2026-02-05T17:37:39.536722Z","steps":["trace[1525223266] 'agreement among raft nodes before linearized reading' (duration: 383.977103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:39.536748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:39.152633Z","time spent":"384.111207ms","remote":"127.0.0.1:42090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-05T17:37:39.536865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.681438ms","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-05T17:37:39.536881Z","caller":"traceutil/trace.go:171","msg":"trace[490938327] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:572; }","duration":"184.726059ms","start":"2026-02-05T17:37:39.35215Z","end":"2026-02-05T17:37:39.536876Z","steps":["trace[490938327] 'agreement among raft nodes before linearized reading' (duration: 184.678728ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:46.451622Z","caller":"traceutil/trace.go:171","msg":"trace[1610474994] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"938.933782ms","start":"2026-02-05T17:37:45.512667Z","end":"2026-02-05T17:37:46.451601Z","steps":["trace[1610474994] 'process raft request' (duration: 938.796268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:46.451806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:45.512649Z","time spent":"939.038805ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:46.476519Z","caller":"traceutil/trace.go:171","msg":"trace[1650548396] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"263.160835ms","start":"2026-02-05T17:37:46.213325Z","end":"2026-02-05T17:37:46.476486Z","steps":["trace[1650548396] 'read index received' (duration: 238.945656ms)","trace[1650548396] 'applied index is now lower than readState.Index' (duration: 24.214309ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:46.476876Z","caller":"traceutil/trace.go:171","msg":"trace[1362178729] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"478.212759ms","start":"2026-02-05T17:37:45.998653Z","end":"2026-02-05T17:37:46.476865Z","steps":["trace[1362178729] 'process raft request' (duration: 477.643914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:46.476992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:45.998634Z","time spent":"478.273901ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:46.477497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.165502ms","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-05T17:37:46.477534Z","caller":"traceutil/trace.go:171","msg":"trace[1884045608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"264.232144ms","start":"2026-02-05T17:37:46.213292Z","end":"2026-02-05T17:37:46.477524Z","steps":["trace[1884045608] 'agreement among raft nodes before linearized reading' (duration: 264.088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:46.477778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.562178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:37:46.477811Z","caller":"traceutil/trace.go:171","msg":"trace[631457781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"255.607479ms","start":"2026-02-05T17:37:46.222194Z","end":"2026-02-05T17:37:46.477801Z","steps":["trace[631457781] 'agreement among raft nodes before linearized reading' (duration: 255.514996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:46.478017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.273247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T17:37:46.478045Z","caller":"traceutil/trace.go:171","msg":"trace[1339276021] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:596; }","duration":"227.310809ms","start":"2026-02-05T17:37:46.250726Z","end":"2026-02-05T17:37:46.478037Z","steps":["trace[1339276021] 'agreement among raft nodes before linearized reading' (duration: 227.236017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:46.478211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.155279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:37:46.478322Z","caller":"traceutil/trace.go:171","msg":"trace[1955022046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"240.199999ms","start":"2026-02-05T17:37:46.238035Z","end":"2026-02-05T17:37:46.478235Z","steps":["trace[1955022046] 'agreement among raft nodes before linearized reading' (duration: 240.121537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:46.478506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.389442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:37:46.478536Z","caller":"traceutil/trace.go:171","msg":"trace[784415740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"241.436603ms","start":"2026-02-05T17:37:46.237089Z","end":"2026-02-05T17:37:46.478525Z","steps":["trace[784415740] 'agreement among raft nodes before linearized reading' (duration: 241.360661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:46.478685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.927327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T17:37:46.478711Z","caller":"traceutil/trace.go:171","msg":"trace[1179450413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:596; }","duration":"248.997949ms","start":"2026-02-05T17:37:46.229705Z","end":"2026-02-05T17:37:46.478703Z","steps":["trace[1179450413] 'agreement among raft nodes before linearized reading' (duration: 248.927127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:47.261388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:37:47.761947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:37:48.206582Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.720595411s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T17:37:48.604922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.323425ms","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-05T17:37:48.605051Z","caller":"traceutil/trace.go:171","msg":"trace[1369071670] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:596; }","duration":"592.458789ms","start":"2026-02-05T17:37:48.012575Z","end":"2026-02-05T17:37:48.605034Z","steps":["trace[1369071670] 'range keys from in-memory index tree' (duration: 592.309785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:48.605493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.715575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:37:48.605548Z","caller":"traceutil/trace.go:171","msg":"trace[1002166592] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.119623834s","start":"2026-02-05T17:37:46.485912Z","end":"2026-02-05T17:37:48.605536Z","steps":["trace[1002166592] 'process raft request' (duration: 1.720811356s)","trace[1002166592] 'compare' (duration: 397.388459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:48.605596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:46.485893Z","time spent":"2.119677985s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:48.660287Z","caller":"traceutil/trace.go:171","msg":"trace[1846078009] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:612; }","duration":"1.899152112s","start":"2026-02-05T17:37:46.761114Z","end":"2026-02-05T17:37:48.660266Z","steps":["trace[1846078009] 'read index received' (duration: 1.445620906s)","trace[1846078009] 'applied index is now lower than readState.Index' (duration: 453.530216ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:48.660354Z","caller":"traceutil/trace.go:171","msg":"trace[2048374473] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.172805672s","start":"2026-02-05T17:37:46.487512Z","end":"2026-02-05T17:37:48.660318Z","steps":["trace[2048374473] 'process raft request' (duration: 2.172520364s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:48.660416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.899281756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:48.660451Z","caller":"traceutil/trace.go:171","msg":"trace[2008621869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.899356777s","start":"2026-02-05T17:37:46.761083Z","end":"2026-02-05T17:37:48.660439Z","steps":["trace[2008621869] 'agreement among raft nodes before linearized reading' (duration: 1.899268125s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:48.660484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:46.761068Z","time spent":"1.899408039s","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:37:48.660506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:46.487504Z","time spent":"2.172921725s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:48.660745Z","caller":"traceutil/trace.go:171","msg":"trace[1337910127] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.173149441s","start":"2026-02-05T17:37:46.487558Z","end":"2026-02-05T17:37:48.660708Z","steps":["trace[1337910127] 'process raft request' (duration: 2.172557215s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:48.660781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:46.487362Z","time spent":"2.173413168s","remote":"127.0.0.1:42066","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T17:37:48.660878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:46.487554Z","time spent":"2.173233064s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:48.6611Z","caller":"traceutil/trace.go:171","msg":"trace[896318285] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"2.172148414s","start":"2026-02-05T17:37:46.48894Z","end":"2026-02-05T17:37:48.661088Z","steps":["trace[896318285] 'process raft request' (duration: 2.171243609s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:48.661162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:46.488934Z","time spent":"2.172196055s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:48.661277Z","caller":"traceutil/trace.go:171","msg":"trace[1939173335] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"2.171416774s","start":"2026-02-05T17:37:46.489852Z","end":"2026-02-05T17:37:48.661269Z","steps":["trace[1939173335] 'process raft request' (duration: 2.170380956s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:48.661323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:46.489846Z","time spent":"2.171451895s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:51.902825Z","caller":"traceutil/trace.go:171","msg":"trace[937245007] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"143.726103ms","start":"2026-02-05T17:37:51.759071Z","end":"2026-02-05T17:37:51.902797Z","steps":["trace[937245007] 'read index received' (duration: 143.587109ms)","trace[937245007] 'applied index is now lower than readState.Index' (duration: 137.814µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:51.902863Z","caller":"traceutil/trace.go:171","msg":"trace[186938523] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"171.971591ms","start":"2026-02-05T17:37:51.730846Z","end":"2026-02-05T17:37:51.902818Z","steps":["trace[186938523] 'process raft request' (duration: 171.814127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:51.902963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.881137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:51.902999Z","caller":"traceutil/trace.go:171","msg":"trace[2018095905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"143.955979ms","start":"2026-02-05T17:37:51.759031Z","end":"2026-02-05T17:37:51.902987Z","steps":["trace[2018095905] 'agreement among raft nodes before linearized reading' (duration: 143.867417ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:53.023387Z","caller":"traceutil/trace.go:171","msg":"trace[288853520] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"343.605714ms","start":"2026-02-05T17:37:52.679767Z","end":"2026-02-05T17:37:53.023373Z","steps":["trace[288853520] 'read index received' (duration: 343.43103ms)","trace[288853520] 'applied index is now lower than readState.Index' (duration: 173.954µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:53.023915Z","caller":"traceutil/trace.go:171","msg":"trace[631252903] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"345.988529ms","start":"2026-02-05T17:37:52.677919Z","end":"2026-02-05T17:37:53.023908Z","steps":["trace[631252903] 'process raft request' (duration: 345.317891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:53.023988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:52.677904Z","time spent":"346.03277ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:53.024127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.357755ms","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-05T17:37:53.024147Z","caller":"traceutil/trace.go:171","msg":"trace[1386026265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"344.390855ms","start":"2026-02-05T17:37:52.679751Z","end":"2026-02-05T17:37:53.024142Z","steps":["trace[1386026265] 'agreement among raft nodes before linearized reading' (duration: 344.325293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:53.024162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:52.679745Z","time spent":"344.414366ms","remote":"127.0.0.1:42302","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-05T17:37:53.02424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.437317ms","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-05T17:37:53.024257Z","caller":"traceutil/trace.go:171","msg":"trace[891545719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"344.464707ms","start":"2026-02-05T17:37:52.679789Z","end":"2026-02-05T17:37:53.024254Z","steps":["trace[891545719] 'agreement among raft nodes before linearized reading' (duration: 344.422716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:53.024274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:52.679785Z","time spent":"344.484368ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:37:53.024365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.504843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:53.024382Z","caller":"traceutil/trace.go:171","msg":"trace[643340853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"263.558425ms","start":"2026-02-05T17:37:52.76082Z","end":"2026-02-05T17:37:53.024378Z","steps":["trace[643340853] 'agreement among raft nodes before linearized reading' (duration: 263.533154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:53.024467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.093857ms","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-05T17:37:53.024483Z","caller":"traceutil/trace.go:171","msg":"trace[1786371902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"344.120178ms","start":"2026-02-05T17:37:52.680359Z","end":"2026-02-05T17:37:53.024479Z","steps":["trace[1786371902] 'agreement among raft nodes before linearized reading' (duration: 344.082007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:53.024495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:52.680353Z","time spent":"344.138609ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:55.133244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498756,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:37:55.241177Z","caller":"traceutil/trace.go:171","msg":"trace[1147386190] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"608.500937ms","start":"2026-02-05T17:37:54.632642Z","end":"2026-02-05T17:37:55.241143Z","steps":["trace[1147386190] 'read index received' (duration: 608.313431ms)","trace[1147386190] 'applied index is now lower than readState.Index' (duration: 186.406µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:55.241285Z","caller":"traceutil/trace.go:171","msg":"trace[1881613608] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"674.917844ms","start":"2026-02-05T17:37:54.566359Z","end":"2026-02-05T17:37:55.241277Z","steps":["trace[1881613608] 'process raft request' (duration: 674.648467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.24137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:54.566347Z","time spent":"674.964995ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18061,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:55.241525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.252984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:55.241595Z","caller":"traceutil/trace.go:171","msg":"trace[1855510705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"480.315546ms","start":"2026-02-05T17:37:54.761251Z","end":"2026-02-05T17:37:55.241567Z","steps":["trace[1855510705] 'agreement among raft nodes before linearized reading' (duration: 480.226794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.241624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:54.761197Z","time spent":"480.42103ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:37:55.241818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.14336ms","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-05T17:37:55.241845Z","caller":"traceutil/trace.go:171","msg":"trace[839728048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"207.17951ms","start":"2026-02-05T17:37:55.034658Z","end":"2026-02-05T17:37:55.241837Z","steps":["trace[839728048] 'agreement among raft nodes before linearized reading' (duration: 207.076668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.241851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.541941ms","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-05T17:37:55.241873Z","caller":"traceutil/trace.go:171","msg":"trace[603033383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"207.573721ms","start":"2026-02-05T17:37:55.034292Z","end":"2026-02-05T17:37:55.241866Z","steps":["trace[603033383] 'agreement among raft nodes before linearized reading' (duration: 207.486219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.241971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.612703ms","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-05T17:37:55.241987Z","caller":"traceutil/trace.go:171","msg":"trace[315858811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"214.654914ms","start":"2026-02-05T17:37:55.027327Z","end":"2026-02-05T17:37:55.241982Z","steps":["trace[315858811] 'agreement among raft nodes before linearized reading' (duration: 214.608223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.242005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.371ms","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-05T17:37:55.242028Z","caller":"traceutil/trace.go:171","msg":"trace[255845478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:634; }","duration":"609.428481ms","start":"2026-02-05T17:37:54.632591Z","end":"2026-02-05T17:37:55.24202Z","steps":["trace[255845478] 'agreement among raft nodes before linearized reading' (duration: 609.36894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.242046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:54.632572Z","time spent":"609.468892ms","remote":"127.0.0.1:42302","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-05T17:37:55.242131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.133639ms","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-05T17:37:55.242147Z","caller":"traceutil/trace.go:171","msg":"trace[1211890022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"207.15934ms","start":"2026-02-05T17:37:55.034982Z","end":"2026-02-05T17:37:55.242142Z","steps":["trace[1211890022] 'agreement among raft nodes before linearized reading' (duration: 207.109248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.796273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.327561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:37:55.796364Z","caller":"traceutil/trace.go:171","msg":"trace[192924155] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"545.372137ms","start":"2026-02-05T17:37:55.250975Z","end":"2026-02-05T17:37:55.796347Z","steps":["trace[192924155] 'process raft request' (duration: 433.886542ms)","trace[192924155] 'compare' (duration: 111.121465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:55.79642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:55.250957Z","time spent":"545.436859ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:55.838971Z","caller":"traceutil/trace.go:171","msg":"trace[187563645] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:652; }","duration":"586.365333ms","start":"2026-02-05T17:37:55.252592Z","end":"2026-02-05T17:37:55.838957Z","steps":["trace[187563645] 'read index received' (duration: 432.359271ms)","trace[187563645] 'applied index is now lower than readState.Index' (duration: 154.005572ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:55.838987Z","caller":"traceutil/trace.go:171","msg":"trace[2021986578] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"586.465426ms","start":"2026-02-05T17:37:55.252509Z","end":"2026-02-05T17:37:55.838974Z","steps":["trace[2021986578] 'process raft request' (duration: 586.397734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.839057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.458976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:55.839073Z","caller":"traceutil/trace.go:171","msg":"trace[223854045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"586.493996ms","start":"2026-02-05T17:37:55.252574Z","end":"2026-02-05T17:37:55.839068Z","steps":["trace[223854045] 'agreement among raft nodes before linearized reading' (duration: 586.437405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.83907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:55.252502Z","time spent":"586.519887ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:55.839086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:55.25257Z","time spent":"586.513397ms","remote":"127.0.0.1:42052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:37:55.839188Z","caller":"traceutil/trace.go:171","msg":"trace[1106706263] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"585.957072ms","start":"2026-02-05T17:37:55.253223Z","end":"2026-02-05T17:37:55.83918Z","steps":["trace[1106706263] 'process raft request' (duration: 585.701456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.839214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:55.253218Z","time spent":"585.980693ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:37:55.839253Z","caller":"traceutil/trace.go:171","msg":"trace[1108244892] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"588.178882ms","start":"2026-02-05T17:37:55.251067Z","end":"2026-02-05T17:37:55.839246Z","steps":["trace[1108244892] 'process raft request' (duration: 587.766241ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:55.839273Z","caller":"traceutil/trace.go:171","msg":"trace[2128954141] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"585.49492ms","start":"2026-02-05T17:37:55.253774Z","end":"2026-02-05T17:37:55.839269Z","steps":["trace[2128954141] 'process raft request' (duration: 585.167431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:55.839305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:55.253769Z","time spent":"585.51591ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:55.839311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:55.251062Z","time spent":"588.207353ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:57.259537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:37:57.760486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498774,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:37:58.018742Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.400801045s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:37:58.067859Z","caller":"traceutil/trace.go:171","msg":"trace[392060965] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.489722465s","start":"2026-02-05T17:37:56.578118Z","end":"2026-02-05T17:37:58.067841Z","steps":["trace[392060965] 'process raft request' (duration: 1.440785213s)","trace[392060965] 'compare' (duration: 48.86595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:58.067951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:56.57811Z","time spent":"1.489792827s","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18056,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:58.43802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.216242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:37:58.438078Z","caller":"traceutil/trace.go:171","msg":"trace[839895607] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"1.679558123s","start":"2026-02-05T17:37:56.758509Z","end":"2026-02-05T17:37:58.438068Z","steps":["trace[839895607] 'read index received' (duration: 1.260458494s)","trace[839895607] 'applied index is now lower than readState.Index' (duration: 419.098679ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:58.438127Z","caller":"traceutil/trace.go:171","msg":"trace[71808204] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.798316726s","start":"2026-02-05T17:37:56.639803Z","end":"2026-02-05T17:37:58.43812Z","steps":["trace[71808204] 'process raft request' (duration: 1.558938729s)","trace[71808204] 'compare' (duration: 239.079519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:58.438154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:56.639786Z","time spent":"1.798354017s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:58.438237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.118692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:37:58.438314Z","caller":"traceutil/trace.go:171","msg":"trace[1723981768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"638.203404ms","start":"2026-02-05T17:37:57.8001Z","end":"2026-02-05T17:37:58.438304Z","steps":["trace[1723981768] 'agreement among raft nodes before linearized reading' (duration: 638.05756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:58.438337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:57.800082Z","time spent":"638.249816ms","remote":"127.0.0.1:42302","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":"warn","ts":"2026-02-05T17:37:58.438398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.390141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:37:58.438415Z","caller":"traceutil/trace.go:171","msg":"trace[2110269548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"594.775142ms","start":"2026-02-05T17:37:57.843635Z","end":"2026-02-05T17:37:58.43841Z","steps":["trace[2110269548] 'agreement among raft nodes before linearized reading' (duration: 594.69642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:58.438432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:57.84362Z","time spent":"594.809263ms","remote":"127.0.0.1:42302","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-05T17:37:58.438459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.479424ms","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-05T17:37:58.438474Z","caller":"traceutil/trace.go:171","msg":"trace[1198908962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"594.507374ms","start":"2026-02-05T17:37:57.843963Z","end":"2026-02-05T17:37:58.43847Z","steps":["trace[1198908962] 'agreement among raft nodes before linearized reading' (duration: 594.462203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:58.438487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:57.843958Z","time spent":"594.525725ms","remote":"127.0.0.1:42302","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-05T17:37:58.4385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.680001565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:58.438514Z","caller":"traceutil/trace.go:171","msg":"trace[2066218152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.680099479s","start":"2026-02-05T17:37:56.758409Z","end":"2026-02-05T17:37:58.438509Z","steps":["trace[2066218152] 'agreement among raft nodes before linearized reading' (duration: 1.680076578s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:58.438526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:56.758395Z","time spent":"1.680127919s","remote":"127.0.0.1:42052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:37:58.438624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.676603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-05T17:37:58.43863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.67762ms","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-05T17:37:58.43864Z","caller":"traceutil/trace.go:171","msg":"trace[1092182910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"593.693533ms","start":"2026-02-05T17:37:57.844942Z","end":"2026-02-05T17:37:58.438635Z","steps":["trace[1092182910] 'agreement among raft nodes before linearized reading' (duration: 593.645442ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:58.438645Z","caller":"traceutil/trace.go:171","msg":"trace[1437035689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:643; }","duration":"594.71855ms","start":"2026-02-05T17:37:57.843922Z","end":"2026-02-05T17:37:58.438641Z","steps":["trace[1437035689] 'agreement among raft nodes before linearized reading' (duration: 594.674279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:58.43866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:57.843914Z","time spent":"594.743582ms","remote":"127.0.0.1:42302","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-05T17:37:58.438662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:57.844916Z","time spent":"593.741514ms","remote":"127.0.0.1:42302","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-05T17:38:04.725409Z","caller":"traceutil/trace.go:171","msg":"trace[24845427] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"133.72696ms","start":"2026-02-05T17:38:04.591659Z","end":"2026-02-05T17:38:04.725386Z","steps":["trace[24845427] 'read index received' (duration: 133.546935ms)","trace[24845427] 'applied index is now lower than readState.Index' (duration: 179.145µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:04.726288Z","caller":"traceutil/trace.go:171","msg":"trace[1432416344] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"147.331591ms","start":"2026-02-05T17:38:04.578941Z","end":"2026-02-05T17:38:04.726273Z","steps":["trace[1432416344] 'process raft request' (duration: 146.319274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:04.727345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.641412ms","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-05T17:38:04.727395Z","caller":"traceutil/trace.go:171","msg":"trace[432348207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"135.740835ms","start":"2026-02-05T17:38:04.59164Z","end":"2026-02-05T17:38:04.727381Z","steps":["trace[432348207] 'agreement among raft nodes before linearized reading' (duration: 135.397636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:05.261953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:38:05.762226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576498865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:38:05.943835Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.208708215s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T17:38:06.761052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000124981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-05T17:38:06.761128Z","caller":"traceutil/trace.go:171","msg":"trace[422833658] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000255934s","start":"2026-02-05T17:38:04.760856Z","end":"2026-02-05T17:38:06.761112Z","steps":["trace[422833658] 'agreement among raft nodes before linearized reading' (duration: 2.000168222s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:06.761174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:04.760846Z","time spent":"2.000316146s","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/05 17:38:06 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-05T17:38:06.841579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.474763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:38:06.843384Z","caller":"traceutil/trace.go:171","msg":"trace[2136232986] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"2.106582269s","start":"2026-02-05T17:38:04.735072Z","end":"2026-02-05T17:38:06.841654Z","steps":["trace[2136232986] 'process raft request' (duration: 1.208946892s)","trace[2136232986] 'compare' (duration: 896.925557ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:06.843457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:04.735058Z","time spent":"2.108363808s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:07.125608Z","caller":"traceutil/trace.go:171","msg":"trace[644693273] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"2.38949126s","start":"2026-02-05T17:38:04.736095Z","end":"2026-02-05T17:38:07.125586Z","steps":["trace[644693273] 'process raft request' (duration: 2.337456504s)","trace[644693273] 'compare' (duration: 51.898853ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:07.125755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:04.736087Z","time spent":"2.389597753s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:07.125915Z","caller":"traceutil/trace.go:171","msg":"trace[393480499] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"2.387917888s","start":"2026-02-05T17:38:04.737974Z","end":"2026-02-05T17:38:07.125892Z","steps":["trace[393480499] 'process raft request' (duration: 2.387580879s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:07.12604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:04.737966Z","time spent":"2.38800709s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:07.126198Z","caller":"traceutil/trace.go:171","msg":"trace[1261871147] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:707; }","duration":"2.365279672s","start":"2026-02-05T17:38:04.760907Z","end":"2026-02-05T17:38:07.126187Z","steps":["trace[1261871147] 'read index received' (duration: 1.18315573s)","trace[1261871147] 'applied index is now lower than readState.Index' (duration: 1.182123272s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:07.126453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.883951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-05T17:38:07.126466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.282184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:38:07.126492Z","caller":"traceutil/trace.go:171","msg":"trace[235606625] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:690; }","duration":"873.922112ms","start":"2026-02-05T17:38:06.252558Z","end":"2026-02-05T17:38:07.12648Z","steps":["trace[235606625] 'agreement among raft nodes before linearized reading' (duration: 873.802119ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:07.1265Z","caller":"traceutil/trace.go:171","msg":"trace[561861519] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:690; }","duration":"228.345846ms","start":"2026-02-05T17:38:06.898145Z","end":"2026-02-05T17:38:07.126491Z","steps":["trace[561861519] 'agreement among raft nodes before linearized reading' (duration: 228.263404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:07.126524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:06.25248Z","time spent":"874.037175ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"info","ts":"2026-02-05T17:38:07.126639Z","caller":"traceutil/trace.go:171","msg":"trace[1067438861] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"2.387062335s","start":"2026-02-05T17:38:04.739566Z","end":"2026-02-05T17:38:07.126628Z","steps":["trace[1067438861] 'process raft request' (duration: 2.386255103s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:07.126688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:04.739558Z","time spent":"2.387102226s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:07.126708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.307015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:07.12676Z","caller":"traceutil/trace.go:171","msg":"trace[932743576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"365.390787ms","start":"2026-02-05T17:38:06.761355Z","end":"2026-02-05T17:38:07.126746Z","steps":["trace[932743576] 'agreement among raft nodes before linearized reading' (duration: 365.310015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:07.126795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:06.761308Z","time spent":"365.478559ms","remote":"127.0.0.1:42052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:38:07.127007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.535543ms","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-05T17:38:07.127037Z","caller":"traceutil/trace.go:171","msg":"trace[10798011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"393.600245ms","start":"2026-02-05T17:38:06.733428Z","end":"2026-02-05T17:38:07.127028Z","steps":["trace[10798011] 'agreement among raft nodes before linearized reading' (duration: 393.515863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:07.127064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:06.733408Z","time spent":"393.649997ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-05T17:38:09.84507Z","caller":"traceutil/trace.go:171","msg":"trace[15039559] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"174.909142ms","start":"2026-02-05T17:38:09.670141Z","end":"2026-02-05T17:38:09.84505Z","steps":["trace[15039559] 'process raft request' (duration: 174.772608ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:09.956297Z","caller":"traceutil/trace.go:171","msg":"trace[1696726285] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"108.019311ms","start":"2026-02-05T17:38:09.848258Z","end":"2026-02-05T17:38:09.956277Z","steps":["trace[1696726285] 'read index received' (duration: 107.341123ms)","trace[1696726285] 'applied index is now lower than readState.Index' (duration: 677.458µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:09.956376Z","caller":"traceutil/trace.go:171","msg":"trace[2044179473] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"173.161445ms","start":"2026-02-05T17:38:09.783208Z","end":"2026-02-05T17:38:09.956369Z","steps":["trace[2044179473] 'process raft request' (duration: 172.383633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:09.956471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.200046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:09.956512Z","caller":"traceutil/trace.go:171","msg":"trace[1110855396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"108.277357ms","start":"2026-02-05T17:38:09.848224Z","end":"2026-02-05T17:38:09.956501Z","steps":["trace[1110855396] 'agreement among raft nodes before linearized reading' (duration: 108.206005ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:09.962412Z","caller":"traceutil/trace.go:171","msg":"trace[1557328700] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"110.436127ms","start":"2026-02-05T17:38:09.851968Z","end":"2026-02-05T17:38:09.962404Z","steps":["trace[1557328700] 'process raft request' (duration: 110.324594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:09.962572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.089774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-qblcd\" ","response":"range_response_count:1 size:4989"} {"level":"info","ts":"2026-02-05T17:38:09.962591Z","caller":"traceutil/trace.go:171","msg":"trace[96801890] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-qblcd; range_end:; response_count:1; response_revision:716; }","duration":"111.131086ms","start":"2026-02-05T17:38:09.851452Z","end":"2026-02-05T17:38:09.962583Z","steps":["trace[96801890] 'agreement among raft nodes before linearized reading' (duration: 111.044414ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:09.962748Z","caller":"traceutil/trace.go:171","msg":"trace[587672231] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"110.681423ms","start":"2026-02-05T17:38:09.85206Z","end":"2026-02-05T17:38:09.962742Z","steps":["trace[587672231] 'process raft request' (duration: 110.305823ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:13.342194Z","caller":"traceutil/trace.go:171","msg":"trace[499293392] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"108.732731ms","start":"2026-02-05T17:38:13.233441Z","end":"2026-02-05T17:38:13.342174Z","steps":["trace[499293392] 'process raft request' (duration: 44.895443ms)","trace[499293392] 'compare' (duration: 63.768406ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:13.347098Z","caller":"traceutil/trace.go:171","msg":"trace[1656747059] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"113.498201ms","start":"2026-02-05T17:38:13.23359Z","end":"2026-02-05T17:38:13.347088Z","steps":["trace[1656747059] 'process raft request' (duration: 113.45579ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:17.076435Z","caller":"traceutil/trace.go:171","msg":"trace[2119349842] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"171.92191ms","start":"2026-02-05T17:38:16.9045Z","end":"2026-02-05T17:38:17.076422Z","steps":["trace[2119349842] 'process raft request' (duration: 171.834048ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:17.462414Z","caller":"traceutil/trace.go:171","msg":"trace[1245841920] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"219.232728ms","start":"2026-02-05T17:38:17.243164Z","end":"2026-02-05T17:38:17.462396Z","steps":["trace[1245841920] 'process raft request' (duration: 219.136125ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:17.493478Z","caller":"traceutil/trace.go:171","msg":"trace[1498416353] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:785; }","duration":"139.94717ms","start":"2026-02-05T17:38:17.353509Z","end":"2026-02-05T17:38:17.493456Z","steps":["trace[1498416353] 'read index received' (duration: 108.74556ms)","trace[1498416353] 'applied index is now lower than readState.Index' (duration: 31.20055ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:17.49358Z","caller":"traceutil/trace.go:171","msg":"trace[433130736] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"248.287099ms","start":"2026-02-05T17:38:17.245284Z","end":"2026-02-05T17:38:17.493571Z","steps":["trace[433130736] 'process raft request' (duration: 248.048232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:17.493858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.751013ms","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-05T17:38:17.493935Z","caller":"traceutil/trace.go:171","msg":"trace[1764875433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"136.862406ms","start":"2026-02-05T17:38:17.357056Z","end":"2026-02-05T17:38:17.493918Z","steps":["trace[1764875433] 'agreement among raft nodes before linearized reading' (duration: 136.692382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:17.494103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.586158ms","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-05T17:38:17.494143Z","caller":"traceutil/trace.go:171","msg":"trace[1031648242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"140.6882ms","start":"2026-02-05T17:38:17.353444Z","end":"2026-02-05T17:38:17.494132Z","steps":["trace[1031648242] 'agreement among raft nodes before linearized reading' (duration: 140.546576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:17.681611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.001717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:38:17.68176Z","caller":"traceutil/trace.go:171","msg":"trace[208925906] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"182.617963ms","start":"2026-02-05T17:38:17.499123Z","end":"2026-02-05T17:38:17.681741Z","steps":["trace[208925906] 'process raft request' (duration: 51.359378ms)","trace[208925906] 'compare' (duration: 130.888644ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:17.700119Z","caller":"traceutil/trace.go:171","msg":"trace[1980562177] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"200.440078ms","start":"2026-02-05T17:38:17.499666Z","end":"2026-02-05T17:38:17.700106Z","steps":["trace[1980562177] 'process raft request' (duration: 200.366466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:19.261973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576499014,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:38:19.270061Z","caller":"traceutil/trace.go:171","msg":"trace[1278468646] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"508.641037ms","start":"2026-02-05T17:38:18.7614Z","end":"2026-02-05T17:38:19.270041Z","steps":["trace[1278468646] 'read index received' (duration: 508.3895ms)","trace[1278468646] 'applied index is now lower than readState.Index' (duration: 250.827µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:19.270366Z","caller":"traceutil/trace.go:171","msg":"trace[655186374] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"724.636579ms","start":"2026-02-05T17:38:18.545718Z","end":"2026-02-05T17:38:19.270355Z","steps":["trace[655186374] 'process raft request' (duration: 724.138786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:19.270453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:18.545698Z","time spent":"724.69429ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:19.270622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.221502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:19.27065Z","caller":"traceutil/trace.go:171","msg":"trace[640908534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"509.278134ms","start":"2026-02-05T17:38:18.761363Z","end":"2026-02-05T17:38:19.270641Z","steps":["trace[640908534] 'agreement among raft nodes before linearized reading' (duration: 509.235073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:19.270675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:18.761348Z","time spent":"509.321776ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:38:19.270858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.936859ms","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-05T17:38:19.270883Z","caller":"traceutil/trace.go:171","msg":"trace[1499137201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"189.999912ms","start":"2026-02-05T17:38:19.080875Z","end":"2026-02-05T17:38:19.270875Z","steps":["trace[1499137201] 'agreement among raft nodes before linearized reading' (duration: 189.917129ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:19.96705Z","caller":"traceutil/trace.go:171","msg":"trace[1604471983] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"501.421611ms","start":"2026-02-05T17:38:19.465611Z","end":"2026-02-05T17:38:19.967033Z","steps":["trace[1604471983] 'read index received' (duration: 501.288877ms)","trace[1604471983] 'applied index is now lower than readState.Index' (duration: 132.144µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:19.967295Z","caller":"traceutil/trace.go:171","msg":"trace[456556248] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"692.429292ms","start":"2026-02-05T17:38:19.274858Z","end":"2026-02-05T17:38:19.967287Z","steps":["trace[456556248] 'process raft request' (duration: 692.090082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:19.967365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:19.274846Z","time spent":"692.467812ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:19.96755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.935735ms","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-05T17:38:19.967571Z","caller":"traceutil/trace.go:171","msg":"trace[2083604377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"501.981525ms","start":"2026-02-05T17:38:19.465584Z","end":"2026-02-05T17:38:19.967565Z","steps":["trace[2083604377] 'agreement among raft nodes before linearized reading' (duration: 501.904523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:19.967605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:19.465571Z","time spent":"502.030067ms","remote":"127.0.0.1:42302","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-05T17:38:19.96772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.599029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T17:38:19.967737Z","caller":"traceutil/trace.go:171","msg":"trace[680103473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"467.650891ms","start":"2026-02-05T17:38:19.500081Z","end":"2026-02-05T17:38:19.967732Z","steps":["trace[680103473] 'agreement among raft nodes before linearized reading' (duration: 467.603679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:19.967752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:19.500064Z","time spent":"467.684592ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:19.967927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.4719ms","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-05T17:38:19.967954Z","caller":"traceutil/trace.go:171","msg":"trace[85635322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"264.517702ms","start":"2026-02-05T17:38:19.703431Z","end":"2026-02-05T17:38:19.967949Z","steps":["trace[85635322] 'agreement among raft nodes before linearized reading' (duration: 264.436519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:19.968072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.974894ms","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-05T17:38:19.968092Z","caller":"traceutil/trace.go:171","msg":"trace[243686551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"283.018815ms","start":"2026-02-05T17:38:19.685069Z","end":"2026-02-05T17:38:19.968087Z","steps":["trace[243686551] 'agreement among raft nodes before linearized reading' (duration: 282.967634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:28.555918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576499091,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:38:28.82462Z","caller":"traceutil/trace.go:171","msg":"trace[794150742] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"769.390498ms","start":"2026-02-05T17:38:28.055212Z","end":"2026-02-05T17:38:28.824602Z","steps":["trace[794150742] 'read index received' (duration: 769.264004ms)","trace[794150742] 'applied index is now lower than readState.Index' (duration: 125.664µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:28.824722Z","caller":"traceutil/trace.go:171","msg":"trace[1476168809] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"822.514834ms","start":"2026-02-05T17:38:28.002199Z","end":"2026-02-05T17:38:28.824714Z","steps":["trace[1476168809] 'process raft request' (duration: 822.291098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:28.824816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.002164Z","time spent":"822.577615ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:28.82497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.757588ms","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-05T17:38:28.82499Z","caller":"traceutil/trace.go:171","msg":"trace[1761364440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"769.799319ms","start":"2026-02-05T17:38:28.055185Z","end":"2026-02-05T17:38:28.824984Z","steps":["trace[1761364440] 'agreement among raft nodes before linearized reading' (duration: 769.722057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:28.825008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.055172Z","time spent":"769.83226ms","remote":"127.0.0.1:42302","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-05T17:38:28.832701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.030688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T17:38:28.832735Z","caller":"traceutil/trace.go:171","msg":"trace[738509331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"708.082049ms","start":"2026-02-05T17:38:28.124644Z","end":"2026-02-05T17:38:28.832726Z","steps":["trace[738509331] 'agreement among raft nodes before linearized reading' (duration: 707.980186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:28.83277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.124637Z","time spent":"708.11542ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:28.83287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.557468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:28.832887Z","caller":"traceutil/trace.go:171","msg":"trace[1200089611] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:793; }","duration":"583.613299ms","start":"2026-02-05T17:38:28.249269Z","end":"2026-02-05T17:38:28.832882Z","steps":["trace[1200089611] 'agreement among raft nodes before linearized reading' (duration: 583.584278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:28.832904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.249237Z","time spent":"583.662631ms","remote":"127.0.0.1:42488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:38:28.833179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.540835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:38:28.833212Z","caller":"traceutil/trace.go:171","msg":"trace[108422064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"714.585036ms","start":"2026-02-05T17:38:28.118616Z","end":"2026-02-05T17:38:28.833201Z","steps":["trace[108422064] 'agreement among raft nodes before linearized reading' (duration: 714.507494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:28.833214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.845878ms","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-05T17:38:28.833236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.118609Z","time spent":"714.621128ms","remote":"127.0.0.1:42302","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-05T17:38:28.833281Z","caller":"traceutil/trace.go:171","msg":"trace[1434168559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"723.988452ms","start":"2026-02-05T17:38:28.109264Z","end":"2026-02-05T17:38:28.833253Z","steps":["trace[1434168559] 'agreement among raft nodes before linearized reading' (duration: 715.865361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:28.833319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.109247Z","time spent":"724.063094ms","remote":"127.0.0.1:42302","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-05T17:38:29.351424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576499094,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:38:29.480485Z","caller":"traceutil/trace.go:171","msg":"trace[795233023] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"644.684961ms","start":"2026-02-05T17:38:28.835776Z","end":"2026-02-05T17:38:29.480461Z","steps":["trace[795233023] 'process raft request' (duration: 599.501001ms)","trace[795233023] 'compare' (duration: 45.069397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:29.480609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.835761Z","time spent":"644.785444ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:29.728822Z","caller":"traceutil/trace.go:171","msg":"trace[1469346613] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:820; }","duration":"878.055006ms","start":"2026-02-05T17:38:28.850751Z","end":"2026-02-05T17:38:29.728806Z","steps":["trace[1469346613] 'read index received' (duration: 584.533544ms)","trace[1469346613] 'applied index is now lower than readState.Index' (duration: 293.520582ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:29.728908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.145668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:29.728937Z","caller":"traceutil/trace.go:171","msg":"trace[1375918763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"878.210409ms","start":"2026-02-05T17:38:28.850721Z","end":"2026-02-05T17:38:29.728931Z","steps":["trace[1375918763] 'agreement among raft nodes before linearized reading' (duration: 878.145848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:29.728957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.85071Z","time spent":"878.242801ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:38:29.729134Z","caller":"traceutil/trace.go:171","msg":"trace[1840649758] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"890.121964ms","start":"2026-02-05T17:38:28.839006Z","end":"2026-02-05T17:38:29.729128Z","steps":["trace[1840649758] 'process raft request' (duration: 881.632083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:29.729209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.838998Z","time spent":"890.173395ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:29.729294Z","caller":"traceutil/trace.go:171","msg":"trace[1815374929] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"890.169985ms","start":"2026-02-05T17:38:28.839117Z","end":"2026-02-05T17:38:29.729287Z","steps":["trace[1815374929] 'process raft request' (duration: 889.637541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:29.729326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.839112Z","time spent":"890.195356ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:29.7294Z","caller":"traceutil/trace.go:171","msg":"trace[1226402780] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"889.295071ms","start":"2026-02-05T17:38:28.8401Z","end":"2026-02-05T17:38:29.729395Z","steps":["trace[1226402780] 'process raft request' (duration: 888.682194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:29.72943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:28.840092Z","time spent":"889.320923ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:29.872317Z","caller":"traceutil/trace.go:171","msg":"trace[881194819] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"110.160719ms","start":"2026-02-05T17:38:29.762137Z","end":"2026-02-05T17:38:29.872298Z","steps":["trace[881194819] 'read index received' (duration: 85.839287ms)","trace[881194819] 'applied index is now lower than readState.Index' (duration: 24.320532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:29.87253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.388155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:29.872572Z","caller":"traceutil/trace.go:171","msg":"trace[1983346535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"110.541269ms","start":"2026-02-05T17:38:29.762023Z","end":"2026-02-05T17:38:29.872564Z","steps":["trace[1983346535] 'agreement among raft nodes before linearized reading' (duration: 110.430286ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:29.872808Z","caller":"traceutil/trace.go:171","msg":"trace[2061152005] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"263.750781ms","start":"2026-02-05T17:38:29.609048Z","end":"2026-02-05T17:38:29.872798Z","steps":["trace[2061152005] 'process raft request' (duration: 238.917875ms)","trace[2061152005] 'compare' (duration: 24.25326ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:41.009054Z","caller":"traceutil/trace.go:171","msg":"trace[1149439480] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"126.462833ms","start":"2026-02-05T17:38:40.88238Z","end":"2026-02-05T17:38:41.008843Z","steps":["trace[1149439480] 'process raft request' (duration: 126.338839ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:41.484801Z","caller":"traceutil/trace.go:171","msg":"trace[1968661170] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"155.225365ms","start":"2026-02-05T17:38:41.32956Z","end":"2026-02-05T17:38:41.484785Z","steps":["trace[1968661170] 'process raft request' (duration: 154.859365ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:43.819583Z","caller":"traceutil/trace.go:171","msg":"trace[863660049] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"284.022783ms","start":"2026-02-05T17:38:43.535544Z","end":"2026-02-05T17:38:43.819567Z","steps":["trace[863660049] 'process raft request' (duration: 283.633563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:43.972546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.448975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-05T17:38:43.972577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.988588ms","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-05T17:38:43.972606Z","caller":"traceutil/trace.go:171","msg":"trace[616142450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:885; }","duration":"114.543958ms","start":"2026-02-05T17:38:43.858048Z","end":"2026-02-05T17:38:43.972592Z","steps":["trace[616142450] 'range keys from in-memory index tree' (duration: 114.347633ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:43.972632Z","caller":"traceutil/trace.go:171","msg":"trace[1848189517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"121.081901ms","start":"2026-02-05T17:38:43.851537Z","end":"2026-02-05T17:38:43.972619Z","steps":["trace[1848189517] 'range keys from in-memory index tree' (duration: 120.872005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:43.972735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.595045ms","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-05T17:38:43.972746Z","caller":"traceutil/trace.go:171","msg":"trace[1678597470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"140.623295ms","start":"2026-02-05T17:38:43.832119Z","end":"2026-02-05T17:38:43.972742Z","steps":["trace[1678597470] 'range keys from in-memory index tree' (duration: 140.547363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:45.804563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52206171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:38:45.804638Z","caller":"traceutil/trace.go:171","msg":"trace[729572300] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:888; }","duration":"1.522164812s","start":"2026-02-05T17:38:44.282457Z","end":"2026-02-05T17:38:45.804621Z","steps":["trace[729572300] 'count revisions from in-memory index tree' (duration: 1.521987418s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:45.80467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:44.282441Z","time spent":"1.522220724s","remote":"127.0.0.1:46250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:38:45.805013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130481364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:45.805036Z","caller":"traceutil/trace.go:171","msg":"trace[91597153] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:888; }","duration":"1.130535736s","start":"2026-02-05T17:38:44.674493Z","end":"2026-02-05T17:38:45.805028Z","steps":["trace[91597153] 'count revisions from in-memory index tree' (duration: 1.130383102s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:45.805054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:44.67448Z","time spent":"1.130568976s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:38:45.805436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.893462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:38:45.805456Z","caller":"traceutil/trace.go:171","msg":"trace[1011865869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:888; }","duration":"775.949604ms","start":"2026-02-05T17:38:45.029499Z","end":"2026-02-05T17:38:45.805449Z","steps":["trace[1011865869] 'range keys from in-memory index tree' (duration: 775.784169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:45.805472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:45.029482Z","time spent":"775.985704ms","remote":"127.0.0.1:42302","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-05T17:38:45.806088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:45.315338Z","time spent":"490.325428ms","remote":"127.0.0.1:42132","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T17:38:45.806183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.329063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:45.806241Z","caller":"traceutil/trace.go:171","msg":"trace[1307551122] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:888; }","duration":"412.403305ms","start":"2026-02-05T17:38:45.393826Z","end":"2026-02-05T17:38:45.806229Z","steps":["trace[1307551122] 'count revisions from in-memory index tree' (duration: 412.242651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:45.806265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:45.39378Z","time spent":"412.479807ms","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-05T17:38:46.174667Z","caller":"traceutil/trace.go:171","msg":"trace[176952855] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"166.25095ms","start":"2026-02-05T17:38:46.008401Z","end":"2026-02-05T17:38:46.174652Z","steps":["trace[176952855] 'read index received' (duration: 163.902138ms)","trace[176952855] 'applied index is now lower than readState.Index' (duration: 2.347732ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:46.174841Z","caller":"traceutil/trace.go:171","msg":"trace[739349950] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"282.527804ms","start":"2026-02-05T17:38:45.892306Z","end":"2026-02-05T17:38:46.174833Z","steps":["trace[739349950] 'process raft request' (duration: 280.041228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:46.175072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.663561ms","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-05T17:38:46.175096Z","caller":"traceutil/trace.go:171","msg":"trace[279130077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"166.724903ms","start":"2026-02-05T17:38:46.008365Z","end":"2026-02-05T17:38:46.17509Z","steps":["trace[279130077] 'agreement among raft nodes before linearized reading' (duration: 166.62321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:46.175184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.84807ms","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-05T17:38:46.175262Z","caller":"traceutil/trace.go:171","msg":"trace[689535635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:890; }","duration":"124.934342ms","start":"2026-02-05T17:38:46.050315Z","end":"2026-02-05T17:38:46.175249Z","steps":["trace[689535635] 'agreement among raft nodes before linearized reading' (duration: 124.728246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:46.175334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.835373ms","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-05T17:38:46.175359Z","caller":"traceutil/trace.go:171","msg":"trace[636988081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"123.904095ms","start":"2026-02-05T17:38:46.051445Z","end":"2026-02-05T17:38:46.17535Z","steps":["trace[636988081] 'agreement among raft nodes before linearized reading' (duration: 123.841893ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:48.130945Z","caller":"traceutil/trace.go:171","msg":"trace[1990459852] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"108.473658ms","start":"2026-02-05T17:38:48.02245Z","end":"2026-02-05T17:38:48.130924Z","steps":["trace[1990459852] 'read index received' (duration: 108.304694ms)","trace[1990459852] 'applied index is now lower than readState.Index' (duration: 168.064µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:48.131079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.611292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:48.13111Z","caller":"traceutil/trace.go:171","msg":"trace[1787562205] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:895; }","duration":"108.717034ms","start":"2026-02-05T17:38:48.022384Z","end":"2026-02-05T17:38:48.131101Z","steps":["trace[1787562205] 'agreement among raft nodes before linearized reading' (duration: 108.618781ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:48.131229Z","caller":"traceutil/trace.go:171","msg":"trace[992449044] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"235.864153ms","start":"2026-02-05T17:38:47.895347Z","end":"2026-02-05T17:38:48.131212Z","steps":["trace[992449044] 'process raft request' (duration: 235.452482ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:50.269013Z","caller":"traceutil/trace.go:171","msg":"trace[10358407] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"130.088727ms","start":"2026-02-05T17:38:50.138911Z","end":"2026-02-05T17:38:50.269Z","steps":["trace[10358407] 'process raft request' (duration: 129.900522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:50.512246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.01319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:38:50.512366Z","caller":"traceutil/trace.go:171","msg":"trace[467082327] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"240.621909ms","start":"2026-02-05T17:38:50.271724Z","end":"2026-02-05T17:38:50.512355Z","steps":["trace[467082327] 'process raft request' (duration: 240.582488ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:50.512577Z","caller":"traceutil/trace.go:171","msg":"trace[1478281477] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"320.677369ms","start":"2026-02-05T17:38:50.191893Z","end":"2026-02-05T17:38:50.512571Z","steps":["trace[1478281477] 'process raft request' (duration: 131.278529ms)","trace[1478281477] 'compare' (duration: 188.883816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:50.512609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:50.191883Z","time spent":"320.708269ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:50.512671Z","caller":"traceutil/trace.go:171","msg":"trace[633059364] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"243.781182ms","start":"2026-02-05T17:38:50.268884Z","end":"2026-02-05T17:38:50.512665Z","steps":["trace[633059364] 'read index received' (duration: 54.367662ms)","trace[633059364] 'applied index is now lower than readState.Index' (duration: 189.41257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:50.512736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.43565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:38:50.512748Z","caller":"traceutil/trace.go:171","msg":"trace[1877656670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"315.459391ms","start":"2026-02-05T17:38:50.197284Z","end":"2026-02-05T17:38:50.512744Z","steps":["trace[1877656670] 'agreement among raft nodes before linearized reading' (duration: 315.39991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:50.512761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:50.197279Z","time spent":"315.478792ms","remote":"127.0.0.1:42302","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-05T17:38:50.51289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.914798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T17:38:50.512905Z","caller":"traceutil/trace.go:171","msg":"trace[1314415917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"308.95004ms","start":"2026-02-05T17:38:50.203951Z","end":"2026-02-05T17:38:50.512901Z","steps":["trace[1314415917] 'agreement among raft nodes before linearized reading' (duration: 308.909489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:50.512918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:50.203947Z","time spent":"308.9681ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:50.513002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.543295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:38:50.513018Z","caller":"traceutil/trace.go:171","msg":"trace[1778398577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"309.559176ms","start":"2026-02-05T17:38:50.203454Z","end":"2026-02-05T17:38:50.513014Z","steps":["trace[1778398577] 'agreement among raft nodes before linearized reading' (duration: 309.513474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:50.513065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:50.203439Z","time spent":"309.590247ms","remote":"127.0.0.1:42302","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":"warn","ts":"2026-02-05T17:38:50.996414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.646965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:38:50.996638Z","caller":"traceutil/trace.go:171","msg":"trace[681663014] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:934; }","duration":"237.832796ms","start":"2026-02-05T17:38:50.758789Z","end":"2026-02-05T17:38:50.996622Z","steps":["trace[681663014] 'read index received' (duration: 200.633596ms)","trace[681663014] 'applied index is now lower than readState.Index' (duration: 37.19817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:50.996719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.925478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:50.996807Z","caller":"traceutil/trace.go:171","msg":"trace[1471800330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"238.045962ms","start":"2026-02-05T17:38:50.758748Z","end":"2026-02-05T17:38:50.996794Z","steps":["trace[1471800330] 'agreement among raft nodes before linearized reading' (duration: 237.921688ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:50.997059Z","caller":"traceutil/trace.go:171","msg":"trace[1160587735] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"479.947265ms","start":"2026-02-05T17:38:50.517104Z","end":"2026-02-05T17:38:50.997052Z","steps":["trace[1160587735] 'process raft request' (duration: 171.591221ms)","trace[1160587735] 'compare' (duration: 307.394719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:50.997099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:50.517094Z","time spent":"479.982326ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:50.997177Z","caller":"traceutil/trace.go:171","msg":"trace[1506825289] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"478.555948ms","start":"2026-02-05T17:38:50.518615Z","end":"2026-02-05T17:38:50.997171Z","steps":["trace[1506825289] 'process raft request' (duration: 477.892251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:50.997205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:50.518607Z","time spent":"478.581999ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:50.997278Z","caller":"traceutil/trace.go:171","msg":"trace[2028281608] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"478.61875ms","start":"2026-02-05T17:38:50.518653Z","end":"2026-02-05T17:38:50.997272Z","steps":["trace[2028281608] 'process raft request' (duration: 477.931352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:50.997313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:50.51865Z","time spent":"478.644711ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:38:52.03433Z","caller":"traceutil/trace.go:171","msg":"trace[1092856326] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"487.993957ms","start":"2026-02-05T17:38:51.546319Z","end":"2026-02-05T17:38:52.034313Z","steps":["trace[1092856326] 'read index received' (duration: 412.259072ms)","trace[1092856326] 'applied index is now lower than readState.Index' (duration: 75.734255ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:52.034577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.240983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:38:52.0346Z","caller":"traceutil/trace.go:171","msg":"trace[462932796] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:905; }","duration":"488.279204ms","start":"2026-02-05T17:38:51.546315Z","end":"2026-02-05T17:38:52.034594Z","steps":["trace[462932796] 'agreement among raft nodes before linearized reading' (duration: 488.223383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:52.034637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:51.54628Z","time spent":"488.336366ms","remote":"127.0.0.1:42166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-05T17:38:52.036827Z","caller":"traceutil/trace.go:171","msg":"trace[398069081] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"333.867767ms","start":"2026-02-05T17:38:51.702784Z","end":"2026-02-05T17:38:52.036652Z","steps":["trace[398069081] 'process raft request' (duration: 331.464913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:52.036963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:51.702761Z","time spent":"334.127523ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:52.382802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.591087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:52.382852Z","caller":"traceutil/trace.go:171","msg":"trace[2006008350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"621.68167ms","start":"2026-02-05T17:38:51.761157Z","end":"2026-02-05T17:38:52.382838Z","steps":["trace[2006008350] 'agreement among raft nodes before linearized reading' (duration: 275.469758ms)","trace[2006008350] 'range keys from in-memory index tree' (duration: 346.13064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:52.382875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:51.761139Z","time spent":"621.73148ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:38:52.383041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.356904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:38:52.383107Z","caller":"traceutil/trace.go:171","msg":"trace[1987542507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"111.427976ms","start":"2026-02-05T17:38:52.271659Z","end":"2026-02-05T17:38:52.383087Z","steps":["trace[1987542507] 'range keys from in-memory index tree' (duration: 111.21101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:55.642234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.080749ms","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-05T17:38:55.642293Z","caller":"traceutil/trace.go:171","msg":"trace[1293277876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"622.14618ms","start":"2026-02-05T17:38:55.020128Z","end":"2026-02-05T17:38:55.642274Z","steps":["trace[1293277876] 'range keys from in-memory index tree' (duration: 621.989606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:55.642318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:55.020125Z","time spent":"622.187971ms","remote":"127.0.0.1:42302","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-05T17:38:55.642462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.366726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T17:38:55.64248Z","caller":"traceutil/trace.go:171","msg":"trace[517831425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:913; }","duration":"622.408207ms","start":"2026-02-05T17:38:55.020066Z","end":"2026-02-05T17:38:55.642474Z","steps":["trace[517831425] 'range keys from in-memory index tree' (duration: 622.260633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:55.642497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:55.020054Z","time spent":"622.439418ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:55.642602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.728629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T17:38:55.642619Z","caller":"traceutil/trace.go:171","msg":"trace[1467881278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:913; }","duration":"621.75566ms","start":"2026-02-05T17:38:55.020858Z","end":"2026-02-05T17:38:55.642614Z","steps":["trace[1467881278] 'range keys from in-memory index tree' (duration: 621.682988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:55.642636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:55.020854Z","time spent":"621.776551ms","remote":"127.0.0.1:42302","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-05T17:38:55.650492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.244218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:38:55.650521Z","caller":"traceutil/trace.go:171","msg":"trace[1719607777] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:913; }","duration":"205.298478ms","start":"2026-02-05T17:38:55.445215Z","end":"2026-02-05T17:38:55.650513Z","steps":["trace[1719607777] 'count revisions from in-memory index tree' (duration: 205.202146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:55.650669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.536139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:38:55.650688Z","caller":"traceutil/trace.go:171","msg":"trace[404725951] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:913; }","duration":"130.57802ms","start":"2026-02-05T17:38:55.520104Z","end":"2026-02-05T17:38:55.650682Z","steps":["trace[404725951] 'count revisions from in-memory index tree' (duration: 130.492148ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:55.843786Z","caller":"traceutil/trace.go:171","msg":"trace[801224084] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"189.687958ms","start":"2026-02-05T17:38:55.654081Z","end":"2026-02-05T17:38:55.843769Z","steps":["trace[801224084] 'process raft request' (duration: 189.403161ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:56.03973Z","caller":"traceutil/trace.go:171","msg":"trace[1996160868] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:946; }","duration":"279.527445ms","start":"2026-02-05T17:38:55.760188Z","end":"2026-02-05T17:38:56.039716Z","steps":["trace[1996160868] 'read index received' (duration: 83.484589ms)","trace[1996160868] 'applied index is now lower than readState.Index' (duration: 196.042226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:56.039818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.619527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:38:56.039834Z","caller":"traceutil/trace.go:171","msg":"trace[1155473757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"279.674919ms","start":"2026-02-05T17:38:55.760154Z","end":"2026-02-05T17:38:56.039829Z","steps":["trace[1155473757] 'agreement among raft nodes before linearized reading' (duration: 279.621327ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:56.040025Z","caller":"traceutil/trace.go:171","msg":"trace[467971934] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"378.421371ms","start":"2026-02-05T17:38:55.661598Z","end":"2026-02-05T17:38:56.040019Z","steps":["trace[467971934] 'process raft request' (duration: 378.091892ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:56.040002Z","caller":"traceutil/trace.go:171","msg":"trace[969324615] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"385.776404ms","start":"2026-02-05T17:38:55.654192Z","end":"2026-02-05T17:38:56.039969Z","steps":["trace[969324615] 'process raft request' (duration: 362.577813ms)","trace[969324615] 'compare' (duration: 22.838002ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:56.040148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:55.654186Z","time spent":"385.876997ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:56.040181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:55.661591Z","time spent":"378.446531ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:56.688656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.834273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:38:56.688713Z","caller":"traceutil/trace.go:171","msg":"trace[420093886] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"273.849135ms","start":"2026-02-05T17:38:56.414854Z","end":"2026-02-05T17:38:56.688703Z","steps":["trace[420093886] 'read index received' (duration: 89.875658ms)","trace[420093886] 'applied index is now lower than readState.Index' (duration: 183.972877ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:56.688767Z","caller":"traceutil/trace.go:171","msg":"trace[719784196] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"293.941224ms","start":"2026-02-05T17:38:56.394821Z","end":"2026-02-05T17:38:56.688762Z","steps":["trace[719784196] 'process raft request' (duration: 109.952577ms)","trace[719784196] 'compare' (duration: 183.676469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:56.688971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.115202ms","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-05T17:38:56.688985Z","caller":"traceutil/trace.go:171","msg":"trace[825498774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"274.152283ms","start":"2026-02-05T17:38:56.414828Z","end":"2026-02-05T17:38:56.68898Z","steps":["trace[825498774] 'agreement among raft nodes before linearized reading' (duration: 274.085941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:56.688987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.481452ms","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-05T17:38:56.689037Z","caller":"traceutil/trace.go:171","msg":"trace[822783294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:917; }","duration":"159.581254ms","start":"2026-02-05T17:38:56.529444Z","end":"2026-02-05T17:38:56.689025Z","steps":["trace[822783294] 'agreement among raft nodes before linearized reading' (duration: 159.387399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:58.552205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576499402,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:38:58.825517Z","caller":"traceutil/trace.go:171","msg":"trace[1315589399] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"774.165595ms","start":"2026-02-05T17:38:58.051312Z","end":"2026-02-05T17:38:58.825478Z","steps":["trace[1315589399] 'read index received' (duration: 773.96291ms)","trace[1315589399] 'applied index is now lower than readState.Index' (duration: 201.805µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:38:58.826009Z","caller":"traceutil/trace.go:171","msg":"trace[1463862002] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"977.821341ms","start":"2026-02-05T17:38:57.848174Z","end":"2026-02-05T17:38:58.825996Z","steps":["trace[1463862002] 'process raft request' (duration: 977.158293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:58.826105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:57.848166Z","time spent":"977.876013ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:38:58.826363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.045488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-05T17:38:58.826397Z","caller":"traceutil/trace.go:171","msg":"trace[1643372698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:920; }","duration":"775.10792ms","start":"2026-02-05T17:38:58.051279Z","end":"2026-02-05T17:38:58.826387Z","steps":["trace[1643372698] 'agreement among raft nodes before linearized reading' (duration: 774.994637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:58.826426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:58.051269Z","time spent":"775.150662ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T17:38:58.826601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.610537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:38:58.826628Z","caller":"traceutil/trace.go:171","msg":"trace[1240937267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"774.658848ms","start":"2026-02-05T17:38:58.051961Z","end":"2026-02-05T17:38:58.82662Z","steps":["trace[1240937267] 'agreement among raft nodes before linearized reading' (duration: 774.586416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:38:58.826654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:38:58.051957Z","time spent":"774.691419ms","remote":"127.0.0.1:42302","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-05T17:38:59.11707Z","caller":"traceutil/trace.go:171","msg":"trace[964790472] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"285.546233ms","start":"2026-02-05T17:38:58.831507Z","end":"2026-02-05T17:38:59.117054Z","steps":["trace[964790472] 'process raft request' (duration: 285.452771ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:59.121803Z","caller":"traceutil/trace.go:171","msg":"trace[1847478436] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:955; }","duration":"258.492751ms","start":"2026-02-05T17:38:58.863293Z","end":"2026-02-05T17:38:59.121786Z","steps":["trace[1847478436] 'read index received' (duration: 253.775866ms)","trace[1847478436] 'applied index is now lower than readState.Index' (duration: 4.716245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:38:59.121938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.626374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:38:59.121965Z","caller":"traceutil/trace.go:171","msg":"trace[82296710] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:924; }","duration":"258.668215ms","start":"2026-02-05T17:38:58.863291Z","end":"2026-02-05T17:38:59.121959Z","steps":["trace[82296710] 'agreement among raft nodes before linearized reading' (duration: 258.557092ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:59.12217Z","caller":"traceutil/trace.go:171","msg":"trace[1614806265] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"290.564026ms","start":"2026-02-05T17:38:58.831598Z","end":"2026-02-05T17:38:59.122162Z","steps":["trace[1614806265] 'process raft request' (duration: 290.043482ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:59.122266Z","caller":"traceutil/trace.go:171","msg":"trace[124790912] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"288.532372ms","start":"2026-02-05T17:38:58.833728Z","end":"2026-02-05T17:38:59.122261Z","steps":["trace[124790912] 'process raft request' (duration: 288.012418ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:38:59.122337Z","caller":"traceutil/trace.go:171","msg":"trace[1666567737] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"288.552072ms","start":"2026-02-05T17:38:58.833779Z","end":"2026-02-05T17:38:59.122331Z","steps":["trace[1666567737] 'process raft request' (duration: 287.984057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:01.624559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576499417,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:39:01.921519Z","caller":"traceutil/trace.go:171","msg":"trace[1478224657] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"799.190286ms","start":"2026-02-05T17:39:01.122306Z","end":"2026-02-05T17:39:01.921496Z","steps":["trace[1478224657] 'process raft request' (duration: 799.080323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:01.921642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:01.122294Z","time spent":"799.285329ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:01.954345Z","caller":"traceutil/trace.go:171","msg":"trace[1495681531] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"829.859974ms","start":"2026-02-05T17:39:01.124469Z","end":"2026-02-05T17:39:01.954329Z","steps":["trace[1495681531] 'read index received' (duration: 797.567483ms)","trace[1495681531] 'applied index is now lower than readState.Index' (duration: 32.291511ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:01.954501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.012998ms","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-05T17:39:01.954519Z","caller":"traceutil/trace.go:171","msg":"trace[2051880236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"830.070719ms","start":"2026-02-05T17:39:01.124443Z","end":"2026-02-05T17:39:01.954514Z","steps":["trace[2051880236] 'agreement among raft nodes before linearized reading' (duration: 829.961257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:01.954538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:01.124437Z","time spent":"830.09717ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:39:01.954632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.955ms","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-05T17:39:01.954706Z","caller":"traceutil/trace.go:171","msg":"trace[1073008906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"829.038852ms","start":"2026-02-05T17:39:01.125652Z","end":"2026-02-05T17:39:01.954691Z","steps":["trace[1073008906] 'agreement among raft nodes before linearized reading' (duration: 828.844857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:01.954748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.051609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T17:39:01.954743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:01.125637Z","time spent":"829.098223ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T17:39:01.954766Z","caller":"traceutil/trace.go:171","msg":"trace[378569972] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:927; }","duration":"619.099281ms","start":"2026-02-05T17:39:01.335661Z","end":"2026-02-05T17:39:01.95476Z","steps":["trace[378569972] 'agreement among raft nodes before linearized reading' (duration: 619.06882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:01.954783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:01.335646Z","time spent":"619.130521ms","remote":"127.0.0.1:58132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:39:01.954859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.151315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:01.954873Z","caller":"traceutil/trace.go:171","msg":"trace[1053987399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"194.189807ms","start":"2026-02-05T17:39:01.760677Z","end":"2026-02-05T17:39:01.954867Z","steps":["trace[1053987399] 'agreement among raft nodes before linearized reading' (duration: 194.170896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:01.955006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.842737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:39:01.95504Z","caller":"traceutil/trace.go:171","msg":"trace[29086974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"828.886918ms","start":"2026-02-05T17:39:01.126143Z","end":"2026-02-05T17:39:01.95503Z","steps":["trace[29086974] 'agreement among raft nodes before linearized reading' (duration: 828.784575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:01.955066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:01.126139Z","time spent":"828.921259ms","remote":"127.0.0.1:42302","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-05T17:39:03.620989Z","caller":"traceutil/trace.go:171","msg":"trace[700819013] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"781.313135ms","start":"2026-02-05T17:39:02.839629Z","end":"2026-02-05T17:39:03.620942Z","steps":["trace[700819013] 'process raft request' (duration: 781.13876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:03.621283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:02.839618Z","time spent":"781.462139ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:04.336412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.564822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:39:04.336488Z","caller":"traceutil/trace.go:171","msg":"trace[89457720] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:936; }","duration":"113.704286ms","start":"2026-02-05T17:39:04.222766Z","end":"2026-02-05T17:39:04.33647Z","steps":["trace[89457720] 'count revisions from in-memory index tree' (duration: 113.49803ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:05.922144Z","caller":"traceutil/trace.go:171","msg":"trace[2103459118] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"286.329123ms","start":"2026-02-05T17:39:05.63579Z","end":"2026-02-05T17:39:05.922119Z","steps":["trace[2103459118] 'read index received' (duration: 286.066336ms)","trace[2103459118] 'applied index is now lower than readState.Index' (duration: 261.737µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:05.922546Z","caller":"traceutil/trace.go:171","msg":"trace[1956994001] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"290.184975ms","start":"2026-02-05T17:39:05.632347Z","end":"2026-02-05T17:39:05.922532Z","steps":["trace[1956994001] 'process raft request' (duration: 289.588939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:05.922814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.030461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:39:05.92286Z","caller":"traceutil/trace.go:171","msg":"trace[812193694] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:937; }","duration":"287.116183ms","start":"2026-02-05T17:39:05.635732Z","end":"2026-02-05T17:39:05.922848Z","steps":["trace[812193694] 'agreement among raft nodes before linearized reading' (duration: 287.024961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:05.923052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.22708ms","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-05T17:39:05.923094Z","caller":"traceutil/trace.go:171","msg":"trace[1434089849] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:937; }","duration":"163.295811ms","start":"2026-02-05T17:39:05.759788Z","end":"2026-02-05T17:39:05.923083Z","steps":["trace[1434089849] 'agreement among raft nodes before linearized reading' (duration: 163.23227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:05.923362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.533107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:05.923407Z","caller":"traceutil/trace.go:171","msg":"trace[100001948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"163.605909ms","start":"2026-02-05T17:39:05.75979Z","end":"2026-02-05T17:39:05.923396Z","steps":["trace[100001948] 'agreement among raft nodes before linearized reading' (duration: 163.543758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:06.499026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.369627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:06.499129Z","caller":"traceutil/trace.go:171","msg":"trace[2095860454] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"520.974096ms","start":"2026-02-05T17:39:05.978143Z","end":"2026-02-05T17:39:06.499117Z","steps":["trace[2095860454] 'read index received' (duration: 199.431954ms)","trace[2095860454] 'applied index is now lower than readState.Index' (duration: 321.541072ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:06.499273Z","caller":"traceutil/trace.go:171","msg":"trace[957662421] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"563.322441ms","start":"2026-02-05T17:39:05.935942Z","end":"2026-02-05T17:39:06.499264Z","steps":["trace[957662421] 'process raft request' (duration: 241.650247ms)","trace[957662421] 'compare' (duration: 321.224933ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:06.499318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:05.935928Z","time spent":"563.367532ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:06.499512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.369136ms","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-05T17:39:06.499573Z","caller":"traceutil/trace.go:171","msg":"trace[946138630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"521.478599ms","start":"2026-02-05T17:39:05.978088Z","end":"2026-02-05T17:39:06.499566Z","steps":["trace[946138630] 'agreement among raft nodes before linearized reading' (duration: 521.362546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:06.499594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:05.978067Z","time spent":"521.52289ms","remote":"127.0.0.1:42302","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-05T17:39:06.499706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.59253ms","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-05T17:39:06.499729Z","caller":"traceutil/trace.go:171","msg":"trace[1217886739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"516.61396ms","start":"2026-02-05T17:39:05.983108Z","end":"2026-02-05T17:39:06.499722Z","steps":["trace[1217886739] 'agreement among raft nodes before linearized reading' (duration: 516.562299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:06.499747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:05.983085Z","time spent":"516.658572ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:39:06.49982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.811021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T17:39:06.499838Z","caller":"traceutil/trace.go:171","msg":"trace[310197006] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:938; }","duration":"241.862352ms","start":"2026-02-05T17:39:06.257971Z","end":"2026-02-05T17:39:06.499834Z","steps":["trace[310197006] 'agreement among raft nodes before linearized reading' (duration: 241.814921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:06.499958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.172634ms","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-05T17:39:06.499981Z","caller":"traceutil/trace.go:171","msg":"trace[531685434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"460.223395ms","start":"2026-02-05T17:39:06.039752Z","end":"2026-02-05T17:39:06.499975Z","steps":["trace[531685434] 'agreement among raft nodes before linearized reading' (duration: 460.172924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:06.500003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.039733Z","time spent":"460.262707ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:39:07.082799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.758465ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-05T17:39:07.082861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.501998Z","time spent":"580.861064ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T17:39:08.009403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.345774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:08.009579Z","caller":"traceutil/trace.go:171","msg":"trace[1892150328] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:973; }","duration":"1.252203321s","start":"2026-02-05T17:39:06.757365Z","end":"2026-02-05T17:39:08.009568Z","steps":["trace[1892150328] 'read index received' (duration: 224.67343ms)","trace[1892150328] 'applied index is now lower than readState.Index' (duration: 1.027529381s)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:08.009732Z","caller":"traceutil/trace.go:171","msg":"trace[582482134] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"1.50268635s","start":"2026-02-05T17:39:06.507036Z","end":"2026-02-05T17:39:08.009722Z","steps":["trace[582482134] 'process raft request' (duration: 988.964826ms)","trace[582482134] 'compare' (duration: 513.111268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:08.009773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.507023Z","time spent":"1.502728801s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:08.009867Z","caller":"traceutil/trace.go:171","msg":"trace[1964220381] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"1.502777063s","start":"2026-02-05T17:39:06.507085Z","end":"2026-02-05T17:39:08.009862Z","steps":["trace[1964220381] 'process raft request' (duration: 1.502388783s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:08.009891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.507081Z","time spent":"1.502796333s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:08.009949Z","caller":"traceutil/trace.go:171","msg":"trace[826531193] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"1.502831264s","start":"2026-02-05T17:39:06.507113Z","end":"2026-02-05T17:39:08.009944Z","steps":["trace[826531193] 'process raft request' (duration: 1.502435344s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:08.009971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.50711Z","time spent":"1.502848914s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:08.010032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252667583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:08.010047Z","caller":"traceutil/trace.go:171","msg":"trace[1075424913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"1.252681694s","start":"2026-02-05T17:39:06.757361Z","end":"2026-02-05T17:39:08.010043Z","steps":["trace[1075424913] 'agreement among raft nodes before linearized reading' (duration: 1.252656493s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:08.010062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:06.757327Z","time spent":"1.252732075s","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:39:08.0792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.670393ms","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-05T17:39:08.079582Z","caller":"traceutil/trace.go:171","msg":"trace[1529287914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"150.094405ms","start":"2026-02-05T17:39:07.929474Z","end":"2026-02-05T17:39:08.079568Z","steps":["trace[1529287914] 'agreement among raft nodes before linearized reading' (duration: 149.604242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:08.079841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.196051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:39:08.080032Z","caller":"traceutil/trace.go:171","msg":"trace[532983618] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:942; }","duration":"108.419767ms","start":"2026-02-05T17:39:07.971601Z","end":"2026-02-05T17:39:08.08002Z","steps":["trace[532983618] 'agreement among raft nodes before linearized reading' (duration: 108.215791ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:08.081041Z","caller":"traceutil/trace.go:171","msg":"trace[794083894] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"997.072539ms","start":"2026-02-05T17:39:07.083959Z","end":"2026-02-05T17:39:08.081032Z","steps":["trace[794083894] 'process raft request' (duration: 994.82346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:08.083987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:07.083947Z","time spent":"999.993136ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:08.574386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.490028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:39:08.574447Z","caller":"traceutil/trace.go:171","msg":"trace[1278175960] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"488.790247ms","start":"2026-02-05T17:39:08.085647Z","end":"2026-02-05T17:39:08.574437Z","steps":["trace[1278175960] 'read index received' (duration: 287.146875ms)","trace[1278175960] 'applied index is now lower than readState.Index' (duration: 201.642702ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:08.574492Z","caller":"traceutil/trace.go:171","msg":"trace[190230540] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"490.637537ms","start":"2026-02-05T17:39:08.083849Z","end":"2026-02-05T17:39:08.574487Z","steps":["trace[190230540] 'process raft request' (duration: 288.957783ms)","trace[190230540] 'compare' (duration: 201.346545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:08.57452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:08.083835Z","time spent":"490.669437ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:39:08.574658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.829052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-05T17:39:08.574724Z","caller":"traceutil/trace.go:171","msg":"trace[474936695] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:943; }","duration":"487.933415ms","start":"2026-02-05T17:39:08.086773Z","end":"2026-02-05T17:39:08.574706Z","steps":["trace[474936695] 'agreement among raft nodes before linearized reading' (duration: 487.800372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:08.574769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.119716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-05T17:39:08.574762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:08.086766Z","time spent":"487.987607ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-05T17:39:08.574786Z","caller":"traceutil/trace.go:171","msg":"trace[518868485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"489.151627ms","start":"2026-02-05T17:39:08.085628Z","end":"2026-02-05T17:39:08.57478Z","steps":["trace[518868485] 'agreement among raft nodes before linearized reading' (duration: 489.128267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:08.574799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:08.085621Z","time spent":"489.175568ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:39:10.617682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.835867778s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-02-05T17:39:10.617795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:09.838509Z","time spent":"779.282951ms","remote":"127.0.0.1:42120","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T17:39:10.617795Z","caller":"traceutil/trace.go:171","msg":"trace[1134974677] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"2.036680988s","start":"2026-02-05T17:39:08.581097Z","end":"2026-02-05T17:39:10.617778Z","steps":["trace[1134974677] 'process raft request' (duration: 200.650216ms)","trace[1134974677] 'compare' (duration: 1.835437396s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:10.617887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:08.581069Z","time spent":"2.03676615s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:10.6179Z","caller":"traceutil/trace.go:171","msg":"trace[2108337585] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"1.805459106s","start":"2026-02-05T17:39:08.812431Z","end":"2026-02-05T17:39:10.617891Z","steps":["trace[2108337585] 'read index received' (duration: 30.69µs)","trace[2108337585] 'applied index is now lower than readState.Index' (duration: 1.805427436s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:10.617981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.805546709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:39:10.617995Z","caller":"traceutil/trace.go:171","msg":"trace[692428130] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:944; }","duration":"1.80558974s","start":"2026-02-05T17:39:08.8124Z","end":"2026-02-05T17:39:10.61799Z","steps":["trace[692428130] 'agreement among raft nodes before linearized reading' (duration: 1.805556189s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:10.618009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:08.812384Z","time spent":"1.805621991s","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:39:10.618086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.04856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:10.618112Z","caller":"traceutil/trace.go:171","msg":"trace[1983359735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"857.101582ms","start":"2026-02-05T17:39:09.761001Z","end":"2026-02-05T17:39:10.618103Z","steps":["trace[1983359735] 'agreement among raft nodes before linearized reading' (duration: 857.065401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:10.618128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:09.760987Z","time spent":"857.137942ms","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:39:10.618415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.751207ms","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-05T17:39:10.618445Z","caller":"traceutil/trace.go:171","msg":"trace[2002725160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:944; }","duration":"600.982073ms","start":"2026-02-05T17:39:10.01745Z","end":"2026-02-05T17:39:10.618433Z","steps":["trace[2002725160] 'agreement among raft nodes before linearized reading' (duration: 600.705516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:10.618468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.017446Z","time spent":"601.016984ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:39:10.61888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.175435ms","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-05T17:39:10.618897Z","caller":"traceutil/trace.go:171","msg":"trace[1564722236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"602.200375ms","start":"2026-02-05T17:39:10.016691Z","end":"2026-02-05T17:39:10.618891Z","steps":["trace[1564722236] 'agreement among raft nodes before linearized reading' (duration: 601.515647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:10.618914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.016686Z","time spent":"602.223966ms","remote":"127.0.0.1:42302","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-05T17:39:10.619279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.018177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:39:10.619298Z","caller":"traceutil/trace.go:171","msg":"trace[1947609315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"603.058718ms","start":"2026-02-05T17:39:10.01623Z","end":"2026-02-05T17:39:10.619289Z","steps":["trace[1947609315] 'agreement among raft nodes before linearized reading' (duration: 603.016857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:10.619313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.016217Z","time spent":"603.093279ms","remote":"127.0.0.1:42302","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-05T17:39:11.141486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576499470,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:39:11.342317Z","caller":"traceutil/trace.go:171","msg":"trace[1922986248] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"721.625322ms","start":"2026-02-05T17:39:10.620656Z","end":"2026-02-05T17:39:11.342282Z","steps":["trace[1922986248] 'process raft request' (duration: 721.481128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:11.342514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.620644Z","time spent":"721.822978ms","remote":"127.0.0.1:42120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:39:11.354224Z","caller":"traceutil/trace.go:171","msg":"trace[301005652] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:982; }","duration":"713.604761ms","start":"2026-02-05T17:39:10.640609Z","end":"2026-02-05T17:39:11.354214Z","steps":["trace[301005652] 'read index received' (duration: 702.214121ms)","trace[301005652] 'applied index is now lower than readState.Index' (duration: 11.39034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:11.354286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.668603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:11.354302Z","caller":"traceutil/trace.go:171","msg":"trace[446764795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"713.717643ms","start":"2026-02-05T17:39:10.640579Z","end":"2026-02-05T17:39:11.354297Z","steps":["trace[446764795] 'agreement among raft nodes before linearized reading' (duration: 713.669832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:11.354316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.640559Z","time spent":"713.754185ms","remote":"127.0.0.1:42052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:39:11.354468Z","caller":"traceutil/trace.go:171","msg":"trace[285715043] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"713.760005ms","start":"2026-02-05T17:39:10.640703Z","end":"2026-02-05T17:39:11.354463Z","steps":["trace[285715043] 'process raft request' (duration: 713.390605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:11.354508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.640692Z","time spent":"713.788845ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:11.354565Z","caller":"traceutil/trace.go:171","msg":"trace[1258701465] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"713.401525ms","start":"2026-02-05T17:39:10.641159Z","end":"2026-02-05T17:39:11.354561Z","steps":["trace[1258701465] 'process raft request' (duration: 712.987524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:11.354596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.641148Z","time spent":"713.435246ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:11.354649Z","caller":"traceutil/trace.go:171","msg":"trace[471778564] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"713.446116ms","start":"2026-02-05T17:39:10.641199Z","end":"2026-02-05T17:39:11.354645Z","steps":["trace[471778564] 'process raft request' (duration: 712.963524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:11.354669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.641195Z","time spent":"713.462957ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:11.354719Z","caller":"traceutil/trace.go:171","msg":"trace[161507860] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"713.479517ms","start":"2026-02-05T17:39:10.641235Z","end":"2026-02-05T17:39:11.354714Z","steps":["trace[161507860] 'process raft request' (duration: 712.941003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:11.35474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:10.637273Z","time spent":"717.455002ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:11.3548Z","caller":"traceutil/trace.go:171","msg":"trace[1669977829] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"244.017359ms","start":"2026-02-05T17:39:11.110779Z","end":"2026-02-05T17:39:11.354796Z","steps":["trace[1669977829] 'process raft request' (duration: 243.420194ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:15.487391Z","caller":"traceutil/trace.go:171","msg":"trace[386805966] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"113.373346ms","start":"2026-02-05T17:39:15.374Z","end":"2026-02-05T17:39:15.487373Z","steps":["trace[386805966] 'read index received' (duration: 54.015603ms)","trace[386805966] 'applied index is now lower than readState.Index' (duration: 59.357073ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:15.487744Z","caller":"traceutil/trace.go:171","msg":"trace[951388079] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"116.205581ms","start":"2026-02-05T17:39:15.371529Z","end":"2026-02-05T17:39:15.487734Z","steps":["trace[951388079] 'process raft request' (duration: 72.184081ms)","trace[951388079] 'compare' (duration: 43.579218ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:15.488553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.539947ms","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-05T17:39:15.48872Z","caller":"traceutil/trace.go:171","msg":"trace[1063209603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"114.736493ms","start":"2026-02-05T17:39:15.373974Z","end":"2026-02-05T17:39:15.488711Z","steps":["trace[1063209603] 'agreement among raft nodes before linearized reading' (duration: 114.492736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:15.488963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.595229ms","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-05T17:39:15.48912Z","caller":"traceutil/trace.go:171","msg":"trace[1523524208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"114.829985ms","start":"2026-02-05T17:39:15.374282Z","end":"2026-02-05T17:39:15.489112Z","steps":["trace[1523524208] 'agreement among raft nodes before linearized reading' (duration: 114.604149ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:21.635784Z","caller":"traceutil/trace.go:171","msg":"trace[369639767] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"131.261668ms","start":"2026-02-05T17:39:21.504508Z","end":"2026-02-05T17:39:21.63577Z","steps":["trace[369639767] 'process raft request' (duration: 131.161185ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:21.80453Z","caller":"traceutil/trace.go:171","msg":"trace[1558362172] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"165.602424ms","start":"2026-02-05T17:39:21.638912Z","end":"2026-02-05T17:39:21.804515Z","steps":["trace[1558362172] 'process raft request' (duration: 165.566343ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:21.804759Z","caller":"traceutil/trace.go:171","msg":"trace[1822366194] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"176.85872ms","start":"2026-02-05T17:39:21.627895Z","end":"2026-02-05T17:39:21.804754Z","steps":["trace[1822366194] 'process raft request' (duration: 174.992651ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:21.804819Z","caller":"traceutil/trace.go:171","msg":"trace[1261017885] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"168.680124ms","start":"2026-02-05T17:39:21.636133Z","end":"2026-02-05T17:39:21.804813Z","steps":["trace[1261017885] 'read index received' (duration: 166.769424ms)","trace[1261017885] 'applied index is now lower than readState.Index' (duration: 1.91034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:39:21.804902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.891434ms","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-05T17:39:21.804916Z","caller":"traceutil/trace.go:171","msg":"trace[1752742684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"238.928715ms","start":"2026-02-05T17:39:21.565983Z","end":"2026-02-05T17:39:21.804912Z","steps":["trace[1752742684] 'agreement among raft nodes before linearized reading' (duration: 238.857733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:21.805024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.082932ms","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-05T17:39:21.805036Z","caller":"traceutil/trace.go:171","msg":"trace[169264725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"230.109702ms","start":"2026-02-05T17:39:21.574922Z","end":"2026-02-05T17:39:21.805032Z","steps":["trace[169264725] 'agreement among raft nodes before linearized reading' (duration: 230.074331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:24.139998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576499606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:39:24.640301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092254936576499606,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:39:24.80463Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.570420723s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:39:24.804875Z","caller":"traceutil/trace.go:171","msg":"trace[853191887] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"1.165218638s","start":"2026-02-05T17:39:23.639641Z","end":"2026-02-05T17:39:24.80486Z","steps":["trace[853191887] 'read index received' (duration: 1.165072124s)","trace[853191887] 'applied index is now lower than readState.Index' (duration: 145.874µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:39:24.805146Z","caller":"traceutil/trace.go:171","msg":"trace[1353572970] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"2.017668727s","start":"2026-02-05T17:39:22.787467Z","end":"2026-02-05T17:39:24.805136Z","steps":["trace[1353572970] 'process raft request' (duration: 2.017294677s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:24.805175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.041829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-05T17:39:24.805217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:22.787452Z","time spent":"2.017715888s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:39:24.805225Z","caller":"traceutil/trace.go:171","msg":"trace[519096111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"994.145162ms","start":"2026-02-05T17:39:23.811066Z","end":"2026-02-05T17:39:24.805211Z","steps":["trace[519096111] 'agreement among raft nodes before linearized reading' (duration: 993.944056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:24.805261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:23.811049Z","time spent":"994.203974ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:39:24.805335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165693231s","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-05T17:39:24.805354Z","caller":"traceutil/trace.go:171","msg":"trace[1445941859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1014; }","duration":"1.165742012s","start":"2026-02-05T17:39:23.639605Z","end":"2026-02-05T17:39:24.805347Z","steps":["trace[1445941859] 'agreement among raft nodes before linearized reading' (duration: 1.165689051s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:24.80537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:23.639592Z","time spent":"1.165774723s","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:39:24.805536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.696559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-02-05T17:39:24.805553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.55883ms","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-05T17:39:24.805572Z","caller":"traceutil/trace.go:171","msg":"trace[1633580593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"970.590911ms","start":"2026-02-05T17:39:23.834974Z","end":"2026-02-05T17:39:24.805565Z","steps":["trace[1633580593] 'agreement among raft nodes before linearized reading' (duration: 970.54043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:24.805592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:23.834967Z","time spent":"970.620952ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:39:24.806377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.819658ms","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-05T17:39:24.806404Z","caller":"traceutil/trace.go:171","msg":"trace[831315193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"978.87545ms","start":"2026-02-05T17:39:23.827521Z","end":"2026-02-05T17:39:24.806397Z","steps":["trace[831315193] 'agreement among raft nodes before linearized reading' (duration: 978.807238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:24.806422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:23.827504Z","time spent":"978.91335ms","remote":"127.0.0.1:42302","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-05T17:39:24.805566Z","caller":"traceutil/trace.go:171","msg":"trace[1842122936] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1014; }","duration":"632.769391ms","start":"2026-02-05T17:39:24.172788Z","end":"2026-02-05T17:39:24.805557Z","steps":["trace[1842122936] 'agreement among raft nodes before linearized reading' (duration: 632.684378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:24.80715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:24.172769Z","time spent":"634.368003ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T17:39:26.562436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:39:26.25998Z","time spent":"302.452618ms","remote":"127.0.0.1:42066","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T17:39:26.696715Z","caller":"traceutil/trace.go:171","msg":"trace[581254485] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"133.325522ms","start":"2026-02-05T17:39:26.563372Z","end":"2026-02-05T17:39:26.696697Z","steps":["trace[581254485] 'process raft request' (duration: 129.854871ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:27.832451Z","caller":"traceutil/trace.go:171","msg":"trace[1295865236] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"271.147893ms","start":"2026-02-05T17:39:27.561278Z","end":"2026-02-05T17:39:27.832426Z","steps":["trace[1295865236] 'process raft request' (duration: 270.791464ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:28.320635Z","caller":"traceutil/trace.go:171","msg":"trace[1529631247] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"122.256022ms","start":"2026-02-05T17:39:28.198361Z","end":"2026-02-05T17:39:28.320617Z","steps":["trace[1529631247] 'process raft request' (duration: 122.151409ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:31.085361Z","caller":"traceutil/trace.go:171","msg":"trace[711834817] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"115.865563ms","start":"2026-02-05T17:39:30.969479Z","end":"2026-02-05T17:39:31.085344Z","steps":["trace[711834817] 'process raft request' (duration: 112.757931ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:31.087994Z","caller":"traceutil/trace.go:171","msg":"trace[490514537] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"118.360508ms","start":"2026-02-05T17:39:30.96962Z","end":"2026-02-05T17:39:31.08798Z","steps":["trace[490514537] 'process raft request' (duration: 117.978278ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:39:31.088116Z","caller":"traceutil/trace.go:171","msg":"trace[1021405892] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"117.144897ms","start":"2026-02-05T17:39:30.970964Z","end":"2026-02-05T17:39:31.088109Z","steps":["trace[1021405892] 'process raft request' (duration: 116.765577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:39:33.259318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.113526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:39:33.259386Z","caller":"traceutil/trace.go:171","msg":"trace[87206655] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1062; }","duration":"106.201048ms","start":"2026-02-05T17:39:33.153169Z","end":"2026-02-05T17:39:33.25937Z","steps":["trace[87206655] 'agreement among raft nodes before linearized reading' (duration: 106.076405ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:11.790305Z","caller":"traceutil/trace.go:171","msg":"trace[48819574] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"512.666493ms","start":"2026-02-05T17:40:11.277615Z","end":"2026-02-05T17:40:11.790282Z","steps":["trace[48819574] 'process raft request' (duration: 512.5576ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:40:11.790508Z","caller":"traceutil/trace.go:171","msg":"trace[2060389736] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1270; }","duration":"352.836418ms","start":"2026-02-05T17:40:11.437663Z","end":"2026-02-05T17:40:11.790499Z","steps":["trace[2060389736] 'read index received' (duration: 352.832388ms)","trace[2060389736] 'applied index is now lower than readState.Index' (duration: 3.5µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:11.790591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.923871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:40:11.790608Z","caller":"traceutil/trace.go:171","msg":"trace[2041563684] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1221; }","duration":"352.945771ms","start":"2026-02-05T17:40:11.437657Z","end":"2026-02-05T17:40:11.790603Z","steps":["trace[2041563684] 'agreement among raft nodes before linearized reading' (duration: 352.89454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:11.790624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:11.437639Z","time spent":"352.981063ms","remote":"127.0.0.1:46250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:40:11.790508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:11.277593Z","time spent":"512.780166ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:40:11.936468Z","caller":"traceutil/trace.go:171","msg":"trace[1787256868] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"145.908808ms","start":"2026-02-05T17:40:11.790541Z","end":"2026-02-05T17:40:11.936449Z","steps":["trace[1787256868] 'read index received' (duration: 85.532975ms)","trace[1787256868] 'applied index is now lower than readState.Index' (duration: 60.374843ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:40:11.936612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.624055ms","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-05T17:40:11.936629Z","caller":"traceutil/trace.go:171","msg":"trace[324780251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1221; }","duration":"457.684087ms","start":"2026-02-05T17:40:11.478939Z","end":"2026-02-05T17:40:11.936623Z","steps":["trace[324780251] 'agreement among raft nodes before linearized reading' (duration: 457.571053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:11.936648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:11.478925Z","time spent":"457.718647ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:40:11.936631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.773174ms","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-05T17:40:11.936688Z","caller":"traceutil/trace.go:171","msg":"trace[555303197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"261.865886ms","start":"2026-02-05T17:40:11.674807Z","end":"2026-02-05T17:40:11.936673Z","steps":["trace[555303197] 'agreement among raft nodes before linearized reading' (duration: 261.66203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:11.936791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.87545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:40:11.936806Z","caller":"traceutil/trace.go:171","msg":"trace[281135647] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1221; }","duration":"266.916801ms","start":"2026-02-05T17:40:11.669885Z","end":"2026-02-05T17:40:11.936802Z","steps":["trace[281135647] 'agreement among raft nodes before linearized reading' (duration: 266.88728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:11.936895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.114437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:40:11.93692Z","caller":"traceutil/trace.go:171","msg":"trace[1951209174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"179.162949ms","start":"2026-02-05T17:40:11.757748Z","end":"2026-02-05T17:40:11.936911Z","steps":["trace[1951209174] 'agreement among raft nodes before linearized reading' (duration: 179.129188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:11.936922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.927381ms","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-05T17:40:11.93694Z","caller":"traceutil/trace.go:171","msg":"trace[2036438174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1221; }","duration":"278.977672ms","start":"2026-02-05T17:40:11.657957Z","end":"2026-02-05T17:40:11.936935Z","steps":["trace[2036438174] 'agreement among raft nodes before linearized reading' (duration: 278.919351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:11.937032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.485529ms","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-05T17:40:11.937044Z","caller":"traceutil/trace.go:171","msg":"trace[826298518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"340.49852ms","start":"2026-02-05T17:40:11.596542Z","end":"2026-02-05T17:40:11.937041Z","steps":["trace[826298518] 'agreement among raft nodes before linearized reading' (duration: 340.460148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:40:11.937056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:40:11.596506Z","time spent":"340.546362ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:40:11.937056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.054648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-05T17:40:11.937077Z","caller":"traceutil/trace.go:171","msg":"trace[1660031087] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1221; }","duration":"255.093588ms","start":"2026-02-05T17:40:11.681976Z","end":"2026-02-05T17:40:11.93707Z","steps":["trace[1660031087] 'agreement among raft nodes before linearized reading' (duration: 255.031617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:48.451649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.525684ms","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-05T17:41:48.451722Z","caller":"traceutil/trace.go:171","msg":"trace[1136096363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1615; }","duration":"159.616086ms","start":"2026-02-05T17:41:48.292087Z","end":"2026-02-05T17:41:48.451703Z","steps":["trace[1136096363] 'agreement among raft nodes before linearized reading' (duration: 68.606175ms)","trace[1136096363] 'range keys from in-memory index tree' (duration: 90.754174ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:20.924167Z","caller":"traceutil/trace.go:171","msg":"trace[265650535] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"122.229955ms","start":"2026-02-05T17:42:20.801913Z","end":"2026-02-05T17:42:20.924143Z","steps":["trace[265650535] 'process raft request' (duration: 121.929997ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:20.928389Z","caller":"traceutil/trace.go:171","msg":"trace[598826459] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"126.12039ms","start":"2026-02-05T17:42:20.802247Z","end":"2026-02-05T17:42:20.928367Z","steps":["trace[598826459] 'process raft request' (duration: 125.834972ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:28.79876Z","caller":"traceutil/trace.go:171","msg":"trace[1519551172] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"106.333418ms","start":"2026-02-05T17:42:28.692399Z","end":"2026-02-05T17:42:28.798733Z","steps":["trace[1519551172] 'process raft request' (duration: 64.888104ms)","trace[1519551172] 'compare' (duration: 40.5503ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:31.22812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.849275ms","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-05T17:42:31.228184Z","caller":"traceutil/trace.go:171","msg":"trace[186185582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1800; }","duration":"124.953218ms","start":"2026-02-05T17:42:31.103217Z","end":"2026-02-05T17:42:31.22817Z","steps":["trace[186185582] 'range keys from in-memory index tree' (duration: 124.699401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:31.725794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.273326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:42:31.726884Z","caller":"traceutil/trace.go:171","msg":"trace[219856383] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"492.927428ms","start":"2026-02-05T17:42:31.233934Z","end":"2026-02-05T17:42:31.726861Z","steps":["trace[219856383] 'process raft request' (duration: 228.510941ms)","trace[219856383] 'compare' (duration: 262.990488ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:31.727112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:31.233924Z","time spent":"493.064761ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:42:32.053133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.042734ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T17:42:32.152115Z","caller":"traceutil/trace.go:171","msg":"trace[1425037020] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"131.935756ms","start":"2026-02-05T17:42:32.02016Z","end":"2026-02-05T17:42:32.152095Z","steps":["trace[1425037020] 'process raft request' (duration: 131.823793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:32.470853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.15863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:42:32.47125Z","caller":"traceutil/trace.go:171","msg":"trace[1434503306] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"259.887864ms","start":"2026-02-05T17:42:32.211343Z","end":"2026-02-05T17:42:32.471231Z","steps":["trace[1434503306] 'read index received' (duration: 2.820345ms)","trace[1434503306] 'applied index is now lower than readState.Index' (duration: 257.065709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:32.471542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.197533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/test-pvc-pod.18916a9c7d786f26\" ","response":"range_response_count:1 size:782"} {"level":"info","ts":"2026-02-05T17:42:32.471707Z","caller":"traceutil/trace.go:171","msg":"trace[1836595194] range","detail":"{range_begin:/registry/events/default/test-pvc-pod.18916a9c7d786f26; range_end:; response_count:1; response_revision:1805; }","duration":"260.414059ms","start":"2026-02-05T17:42:32.211281Z","end":"2026-02-05T17:42:32.471695Z","steps":["trace[1836595194] 'agreement among raft nodes before linearized reading' (duration: 260.122081ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:32.472326Z","caller":"traceutil/trace.go:171","msg":"trace[177342382] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"284.65203ms","start":"2026-02-05T17:42:32.18753Z","end":"2026-02-05T17:42:32.472182Z","steps":["trace[177342382] 'process raft request' (duration: 159.789114ms)","trace[177342382] 'compare' (duration: 123.024556ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:33.323502Z","caller":"traceutil/trace.go:171","msg":"trace[1669652587] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"114.934509ms","start":"2026-02-05T17:42:33.208529Z","end":"2026-02-05T17:42:33.323463Z","steps":["trace[1669652587] 'read index received' (duration: 114.496827ms)","trace[1669652587] 'applied index is now lower than readState.Index' (duration: 436.892µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:33.323928Z","caller":"traceutil/trace.go:171","msg":"trace[2035643063] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"258.117957ms","start":"2026-02-05T17:42:33.065801Z","end":"2026-02-05T17:42:33.323919Z","steps":["trace[2035643063] 'process raft request' (duration: 257.268904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:33.324476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.900024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:42:33.325478Z","caller":"traceutil/trace.go:171","msg":"trace[389609492] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1812; }","duration":"116.939412ms","start":"2026-02-05T17:42:33.208523Z","end":"2026-02-05T17:42:33.325462Z","steps":["trace[389609492] 'agreement among raft nodes before linearized reading' (duration: 115.875804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:33.653519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.952964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-05T17:42:33.653588Z","caller":"traceutil/trace.go:171","msg":"trace[879735122] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1813; }","duration":"187.053217ms","start":"2026-02-05T17:42:33.466518Z","end":"2026-02-05T17:42:33.653571Z","steps":["trace[879735122] 'range keys from in-memory index tree' (duration: 186.824451ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:35.898755Z","caller":"traceutil/trace.go:171","msg":"trace[2079032519] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"161.506861ms","start":"2026-02-05T17:42:35.737233Z","end":"2026-02-05T17:42:35.89874Z","steps":["trace[2079032519] 'process raft request' (duration: 161.420408ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:40.186231Z","caller":"traceutil/trace.go:171","msg":"trace[1746143183] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"198.609387ms","start":"2026-02-05T17:42:39.987602Z","end":"2026-02-05T17:42:40.186212Z","steps":["trace[1746143183] 'process raft request' (duration: 198.510295ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:40.604915Z","caller":"traceutil/trace.go:171","msg":"trace[388726928] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"106.483971ms","start":"2026-02-05T17:42:40.498402Z","end":"2026-02-05T17:42:40.604886Z","steps":["trace[388726928] 'process raft request' (duration: 106.334047ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:41.295826Z","caller":"traceutil/trace.go:171","msg":"trace[1580450538] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"154.885402ms","start":"2026-02-05T17:42:41.140923Z","end":"2026-02-05T17:42:41.295809Z","steps":["trace[1580450538] 'process raft request' (duration: 154.79735ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:41.587614Z","caller":"traceutil/trace.go:171","msg":"trace[1518147054] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"131.71312ms","start":"2026-02-05T17:42:41.455877Z","end":"2026-02-05T17:42:41.58759Z","steps":["trace[1518147054] 'read index received' (duration: 46.067708ms)","trace[1518147054] 'applied index is now lower than readState.Index' (duration: 85.644532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:41.587802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.906765ms","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-05T17:42:41.587859Z","caller":"traceutil/trace.go:171","msg":"trace[736762011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1858; }","duration":"131.975597ms","start":"2026-02-05T17:42:41.455873Z","end":"2026-02-05T17:42:41.587848Z","steps":["trace[736762011] 'agreement among raft nodes before linearized reading' (duration: 131.801112ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:41.588105Z","caller":"traceutil/trace.go:171","msg":"trace[514360502] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"209.929952ms","start":"2026-02-05T17:42:41.378163Z","end":"2026-02-05T17:42:41.588092Z","steps":["trace[514360502] 'process raft request' (duration: 123.831268ms)","trace[514360502] 'compare' (duration: 85.504588ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:49.942987Z","caller":"traceutil/trace.go:171","msg":"trace[1816374542] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"376.645393ms","start":"2026-02-05T17:42:49.566323Z","end":"2026-02-05T17:42:49.942969Z","steps":["trace[1816374542] 'process raft request' (duration: 376.53529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:49.943106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:49.566311Z","time spent":"376.729345ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:49.943473Z","caller":"traceutil/trace.go:171","msg":"trace[1946786358] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"319.125577ms","start":"2026-02-05T17:42:49.624337Z","end":"2026-02-05T17:42:49.943462Z","steps":["trace[1946786358] 'read index received' (duration: 318.463499ms)","trace[1946786358] 'applied index is now lower than readState.Index' (duration: 661.068µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:49.943743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.717922ms","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-05T17:42:49.943772Z","caller":"traceutil/trace.go:171","msg":"trace[1604156497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1896; }","duration":"316.769453ms","start":"2026-02-05T17:42:49.626995Z","end":"2026-02-05T17:42:49.943764Z","steps":["trace[1604156497] 'agreement among raft nodes before linearized reading' (duration: 316.694851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:49.943824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:49.626981Z","time spent":"316.838525ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:42:49.944061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.393022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:42:49.944089Z","caller":"traceutil/trace.go:171","msg":"trace[1677187679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"182.459934ms","start":"2026-02-05T17:42:49.761623Z","end":"2026-02-05T17:42:49.944083Z","steps":["trace[1677187679] 'agreement among raft nodes before linearized reading' (duration: 182.416472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:49.944444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.114403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-05T17:42:49.944471Z","caller":"traceutil/trace.go:171","msg":"trace[1289491865] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1896; }","duration":"320.177745ms","start":"2026-02-05T17:42:49.624287Z","end":"2026-02-05T17:42:49.944465Z","steps":["trace[1289491865] 'agreement among raft nodes before linearized reading' (duration: 319.359493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:49.954119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:49.62427Z","time spent":"329.808964ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:42:50.539347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.845823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:42:50.540349Z","caller":"traceutil/trace.go:171","msg":"trace[1744447338] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"581.58061ms","start":"2026-02-05T17:42:49.958742Z","end":"2026-02-05T17:42:50.540323Z","steps":["trace[1744447338] 'read index received' (duration: 83.69576ms)","trace[1744447338] 'applied index is now lower than readState.Index' (duration: 497.86659ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:50.540441Z","caller":"traceutil/trace.go:171","msg":"trace[336868447] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"581.879248ms","start":"2026-02-05T17:42:49.95855Z","end":"2026-02-05T17:42:50.540429Z","steps":["trace[336868447] 'process raft request' (duration: 83.881184ms)","trace[336868447] 'compare' (duration: 496.35481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:50.540505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:49.958536Z","time spent":"581.937899ms","remote":"127.0.0.1:42120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:42:50.54499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.225665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2833"} {"level":"info","ts":"2026-02-05T17:42:50.545033Z","caller":"traceutil/trace.go:171","msg":"trace[312271337] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1899; }","duration":"586.285547ms","start":"2026-02-05T17:42:49.958738Z","end":"2026-02-05T17:42:50.545024Z","steps":["trace[312271337] 'agreement among raft nodes before linearized reading' (duration: 586.149403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:50.545062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:49.95872Z","time spent":"586.335968ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2856,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-05T17:42:50.545263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.403504ms","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-05T17:42:50.545327Z","caller":"traceutil/trace.go:171","msg":"trace[1356254984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1899; }","duration":"287.496957ms","start":"2026-02-05T17:42:50.257823Z","end":"2026-02-05T17:42:50.54532Z","steps":["trace[1356254984] 'agreement among raft nodes before linearized reading' (duration: 287.398414ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:50.545524Z","caller":"traceutil/trace.go:171","msg":"trace[257840009] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"583.684677ms","start":"2026-02-05T17:42:49.961828Z","end":"2026-02-05T17:42:50.545513Z","steps":["trace[257840009] 'process raft request' (duration: 577.689126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:50.54559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:49.961821Z","time spent":"583.730768ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:50.545749Z","caller":"traceutil/trace.go:171","msg":"trace[1410642421] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"585.410183ms","start":"2026-02-05T17:42:49.96033Z","end":"2026-02-05T17:42:50.545741Z","steps":["trace[1410642421] 'process raft request' (duration: 579.102843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:50.545795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:49.960324Z","time spent":"585.450974ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:54.791766Z","caller":"traceutil/trace.go:171","msg":"trace[395134772] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"158.664964ms","start":"2026-02-05T17:42:54.633073Z","end":"2026-02-05T17:42:54.791738Z","steps":["trace[395134772] 'process raft request' (duration: 127.421434ms)","trace[395134772] 'compare' (duration: 31.133777ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:54.853417Z","caller":"traceutil/trace.go:171","msg":"trace[1087608341] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2002; }","duration":"177.252104ms","start":"2026-02-05T17:42:54.67614Z","end":"2026-02-05T17:42:54.853392Z","steps":["trace[1087608341] 'read index received' (duration: 84.359977ms)","trace[1087608341] 'applied index is now lower than readState.Index' (duration: 92.891247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:54.854028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.86206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:42:54.854081Z","caller":"traceutil/trace.go:171","msg":"trace[2135140601] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1920; }","duration":"177.962703ms","start":"2026-02-05T17:42:54.676106Z","end":"2026-02-05T17:42:54.854069Z","steps":["trace[2135140601] 'agreement among raft nodes before linearized reading' (duration: 177.84708ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:54.85571Z","caller":"traceutil/trace.go:171","msg":"trace[165726605] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"222.515391ms","start":"2026-02-05T17:42:54.633179Z","end":"2026-02-05T17:42:54.855694Z","steps":["trace[165726605] 'process raft request' (duration: 220.097575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:55.156429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.353715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363634"} {"level":"info","ts":"2026-02-05T17:42:55.156487Z","caller":"traceutil/trace.go:171","msg":"trace[1137141127] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1921; }","duration":"139.417057ms","start":"2026-02-05T17:42:55.017052Z","end":"2026-02-05T17:42:55.156469Z","steps":["trace[1137141127] 'range keys from in-memory index tree' (duration: 132.349226ms)"],"step_count":1}