{"level":"warn","ts":"2026-01-30T16:02:32.534756Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T16:02:32.534848Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-30T16:02:32.536433Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T16:02:32.536507Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-01-30T16:02:32.536534Z","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-01-30T16:02:32.537709Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-01-30T16:02:32.537996Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-30T16:02:32.552581Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.461137ms"} {"level":"info","ts":"2026-01-30T16:02:32.585906Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-01-30T16:02:32.585972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T16:02:32.586111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-01-30T16:02:32.586172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T16:02:32.586179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-01-30T16:02:32.586302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-01-30T16:02:32.597688Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T16:02:32.598914Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T16:02:32.601299Z","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-01-30T16:02:32.608115Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T16:02:32.611429Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T16:02:32.611637Z","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-01-30T16:02:32.611669Z","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-01-30T16:02:32.611747Z","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-01-30T16:02:32.612069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-01-30T16:02:32.612235Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-01-30T16:02:32.623487Z","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-01-30T16:02:32.623753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T16:02:32.623778Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T16:02:32.624462Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-01-30T16:02:32.624478Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-01-30T16:02:33.487173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T16:02:33.487211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T16:02:33.487237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-01-30T16:02:33.487253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-01-30T16:02:33.487263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-01-30T16:02:33.487274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-01-30T16:02:33.487285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-01-30T16:02:33.49373Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T16:02:33.4939Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:02:33.496497Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T16:02:33.496807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T16:02:33.496983Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T16:02:33.497025Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T16:02:33.497064Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:02:33.497148Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:02:33.497199Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:02:33.498516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-01-30T16:02:33.499913Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-01-30T16:02:50.137943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.227418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:02:50.138056Z","caller":"traceutil/trace.go:171","msg":"trace[721766488] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"257.494604ms","start":"2026-01-30T16:02:49.880544Z","end":"2026-01-30T16:02:50.138038Z","steps":["trace[721766488] 'process raft request' (duration: 131.617832ms)","trace[721766488] 'compare' (duration: 124.921611ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:02:51.362582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.057026ms","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-01-30T16:02:51.362641Z","caller":"traceutil/trace.go:171","msg":"trace[1884014469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:450; }","duration":"174.127908ms","start":"2026-01-30T16:02:51.188499Z","end":"2026-01-30T16:02:51.362627Z","steps":["trace[1884014469] 'range keys from in-memory index tree' (duration: 174.003866ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:02:51.3631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.511467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T16:02:51.363117Z","caller":"traceutil/trace.go:171","msg":"trace[1374994795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:450; }","duration":"262.549428ms","start":"2026-01-30T16:02:51.10056Z","end":"2026-01-30T16:02:51.36311Z","steps":["trace[1374994795] 'range keys from in-memory index tree' (duration: 262.469356ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:02:51.363178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.089855ms","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-01-30T16:02:51.363191Z","caller":"traceutil/trace.go:171","msg":"trace[1420958266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"186.126165ms","start":"2026-01-30T16:02:51.17706Z","end":"2026-01-30T16:02:51.363186Z","steps":["trace[1420958266] 'range keys from in-memory index tree' (duration: 186.048974ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:02:51.538004Z","caller":"traceutil/trace.go:171","msg":"trace[957395922] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"169.113377ms","start":"2026-01-30T16:02:51.368876Z","end":"2026-01-30T16:02:51.537989Z","steps":["trace[957395922] 'process raft request' (duration: 162.000109ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:02:51.53811Z","caller":"traceutil/trace.go:171","msg":"trace[1120621297] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"169.180929ms","start":"2026-01-30T16:02:51.368924Z","end":"2026-01-30T16:02:51.538105Z","steps":["trace[1120621297] 'process raft request' (duration: 168.76763ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:02:58.580481Z","caller":"traceutil/trace.go:171","msg":"trace[516672741] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"337.62988ms","start":"2026-01-30T16:02:58.242827Z","end":"2026-01-30T16:02:58.580457Z","steps":["trace[516672741] 'process raft request' (duration: 337.478547ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:02:58.580635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:02:58.242804Z","time spent":"337.750653ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:02.651122Z","caller":"traceutil/trace.go:171","msg":"trace[1691053683] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"358.173008ms","start":"2026-01-30T16:03:02.292934Z","end":"2026-01-30T16:03:02.651107Z","steps":["trace[1691053683] 'process raft request' (duration: 358.041135ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:02.651432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:02.292909Z","time spent":"358.461204ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:04.0317Z","caller":"traceutil/trace.go:171","msg":"trace[99655969] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"447.453207ms","start":"2026-01-30T16:03:03.584222Z","end":"2026-01-30T16:03:04.031675Z","steps":["trace[99655969] 'read index received' (duration: 447.294133ms)","trace[99655969] 'applied index is now lower than readState.Index' (duration: 158.504µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:03:04.031779Z","caller":"traceutil/trace.go:171","msg":"trace[663725990] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"537.327569ms","start":"2026-01-30T16:03:03.494446Z","end":"2026-01-30T16:03:04.031774Z","steps":["trace[663725990] 'process raft request' (duration: 537.127634ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:04.031859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:03.494421Z","time spent":"537.37284ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:03:04.032012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.080349ms","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-01-30T16:03:04.032094Z","caller":"traceutil/trace.go:171","msg":"trace[212331879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"359.186721ms","start":"2026-01-30T16:03:03.672889Z","end":"2026-01-30T16:03:04.032076Z","steps":["trace[212331879] 'agreement among raft nodes before linearized reading' (duration: 359.017447ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:04.03213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:03.672877Z","time spent":"359.245412ms","remote":"127.0.0.1:48082","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-01-30T16:03:04.032195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.976098ms","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-01-30T16:03:04.032224Z","caller":"traceutil/trace.go:171","msg":"trace[1856549428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"448.04559ms","start":"2026-01-30T16:03:03.584169Z","end":"2026-01-30T16:03:04.032214Z","steps":["trace[1856549428] 'agreement among raft nodes before linearized reading' (duration: 448.004339ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:04.032254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:03.584155Z","time spent":"448.095431ms","remote":"127.0.0.1:48082","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-01-30T16:03:04.249892Z","caller":"traceutil/trace.go:171","msg":"trace[2083789190] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"203.988254ms","start":"2026-01-30T16:03:04.045887Z","end":"2026-01-30T16:03:04.249875Z","steps":["trace[2083789190] 'process raft request' (duration: 153.326245ms)","trace[2083789190] 'compare' (duration: 50.544886ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:03:04.250021Z","caller":"traceutil/trace.go:171","msg":"trace[2113561038] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"204.025405ms","start":"2026-01-30T16:03:04.04598Z","end":"2026-01-30T16:03:04.250005Z","steps":["trace[2113561038] 'process raft request' (duration: 203.867731ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:04.250179Z","caller":"traceutil/trace.go:171","msg":"trace[75293590] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"202.525301ms","start":"2026-01-30T16:03:04.047648Z","end":"2026-01-30T16:03:04.250174Z","steps":["trace[75293590] 'process raft request' (duration: 202.315307ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:05.87352Z","caller":"traceutil/trace.go:171","msg":"trace[1557191344] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"351.845808ms","start":"2026-01-30T16:03:05.521657Z","end":"2026-01-30T16:03:05.873502Z","steps":["trace[1557191344] 'process raft request' (duration: 351.756576ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:05.873617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:05.52164Z","time spent":"351.927629ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:08.568059Z","caller":"traceutil/trace.go:171","msg":"trace[1060418190] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"227.025097ms","start":"2026-01-30T16:03:08.341011Z","end":"2026-01-30T16:03:08.568036Z","steps":["trace[1060418190] 'read index received' (duration: 226.69516ms)","trace[1060418190] 'applied index is now lower than readState.Index' (duration: 328.587µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:08.568618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.549219ms","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-01-30T16:03:08.568654Z","caller":"traceutil/trace.go:171","msg":"trace[123153197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"227.645791ms","start":"2026-01-30T16:03:08.340997Z","end":"2026-01-30T16:03:08.568643Z","steps":["trace[123153197] 'agreement among raft nodes before linearized reading' (duration: 227.360754ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:08.568797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.738322ms","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-01-30T16:03:08.569508Z","caller":"traceutil/trace.go:171","msg":"trace[1256789769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"228.457498ms","start":"2026-01-30T16:03:08.341028Z","end":"2026-01-30T16:03:08.569485Z","steps":["trace[1256789769] 'agreement among raft nodes before linearized reading' (duration: 227.64572ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:08.568122Z","caller":"traceutil/trace.go:171","msg":"trace[1906088923] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"233.44629ms","start":"2026-01-30T16:03:08.334665Z","end":"2026-01-30T16:03:08.568111Z","steps":["trace[1906088923] 'process raft request' (duration: 233.144933ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:08.942694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.642575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:03:08.942792Z","caller":"traceutil/trace.go:171","msg":"trace[1721849494] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"369.212014ms","start":"2026-01-30T16:03:08.573559Z","end":"2026-01-30T16:03:08.942771Z","steps":["trace[1721849494] 'process raft request' (duration: 130.424955ms)","trace[1721849494] 'compare' (duration: 238.562324ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:08.942854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:08.573545Z","time spent":"369.280365ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:03:09.817412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.008161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:03:09.817756Z","caller":"traceutil/trace.go:171","msg":"trace[2047553070] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:546; }","duration":"815.31958ms","start":"2026-01-30T16:03:09.002422Z","end":"2026-01-30T16:03:09.817741Z","steps":["trace[2047553070] 'read index received' (duration: 53.921461ms)","trace[2047553070] 'applied index is now lower than readState.Index' (duration: 761.397299ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:09.817832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.405663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:09.817858Z","caller":"traceutil/trace.go:171","msg":"trace[494845273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"815.453424ms","start":"2026-01-30T16:03:09.002395Z","end":"2026-01-30T16:03:09.817848Z","steps":["trace[494845273] 'agreement among raft nodes before linearized reading' (duration: 815.390262ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:09.817886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:09.002382Z","time spent":"815.497794ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T16:03:09.818137Z","caller":"traceutil/trace.go:171","msg":"trace[71313100] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.243338155s","start":"2026-01-30T16:03:08.574786Z","end":"2026-01-30T16:03:09.818124Z","steps":["trace[71313100] 'process raft request' (duration: 481.550136ms)","trace[71313100] 'compare' (duration: 760.347197ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:09.818192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:08.574777Z","time spent":"1.243385536s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:09.818332Z","caller":"traceutil/trace.go:171","msg":"trace[1035561869] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"926.602449ms","start":"2026-01-30T16:03:08.891721Z","end":"2026-01-30T16:03:09.818323Z","steps":["trace[1035561869] 'process raft request' (duration: 925.964475ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:09.818379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:08.891698Z","time spent":"926.656101ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:03:10.259488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.267808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jcszr\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-30T16:03:10.259557Z","caller":"traceutil/trace.go:171","msg":"trace[1408028803] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jcszr; range_end:; response_count:1; response_revision:533; }","duration":"133.378191ms","start":"2026-01-30T16:03:10.126161Z","end":"2026-01-30T16:03:10.259539Z","steps":["trace[1408028803] 'range keys from in-memory index tree' (duration: 133.107615ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:12.690069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.042508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:03:12.690132Z","caller":"traceutil/trace.go:171","msg":"trace[872713818] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"504.459846ms","start":"2026-01-30T16:03:12.185661Z","end":"2026-01-30T16:03:12.690121Z","steps":["trace[872713818] 'read index received' (duration: 268.273165ms)","trace[872713818] 'applied index is now lower than readState.Index' (duration: 236.186041ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:03:12.690185Z","caller":"traceutil/trace.go:171","msg":"trace[1827541188] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"520.747239ms","start":"2026-01-30T16:03:12.16943Z","end":"2026-01-30T16:03:12.690177Z","steps":["trace[1827541188] 'process raft request' (duration: 284.552308ms)","trace[1827541188] 'compare' (duration: 235.973336ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:12.690215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:12.169417Z","time spent":"520.78425ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-30T16:03:12.690454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.788364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-v8kl5\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-01-30T16:03:12.690473Z","caller":"traceutil/trace.go:171","msg":"trace[1269227550] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-v8kl5; range_end:; response_count:1; response_revision:548; }","duration":"504.833295ms","start":"2026-01-30T16:03:12.185634Z","end":"2026-01-30T16:03:12.690468Z","steps":["trace[1269227550] 'agreement among raft nodes before linearized reading' (duration: 504.681801ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:12.690492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:12.185571Z","time spent":"504.917006ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4657,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-v8kl5\" "} {"level":"warn","ts":"2026-01-30T16:03:12.690586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.076598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-30T16:03:12.690602Z","caller":"traceutil/trace.go:171","msg":"trace[564191423] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:548; }","duration":"434.11389ms","start":"2026-01-30T16:03:12.256483Z","end":"2026-01-30T16:03:12.690597Z","steps":["trace[564191423] 'agreement among raft nodes before linearized reading' (duration: 434.086069ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:12.69062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:12.25647Z","time spent":"434.14707ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-01-30T16:03:12.690771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.219791ms","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-01-30T16:03:12.690789Z","caller":"traceutil/trace.go:171","msg":"trace[148669019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"108.263191ms","start":"2026-01-30T16:03:12.58252Z","end":"2026-01-30T16:03:12.690783Z","steps":["trace[148669019] 'agreement among raft nodes before linearized reading' (duration: 108.21356ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:12.690906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.809898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T16:03:12.690922Z","caller":"traceutil/trace.go:171","msg":"trace[1068917815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"422.846969ms","start":"2026-01-30T16:03:12.26807Z","end":"2026-01-30T16:03:12.690917Z","steps":["trace[1068917815] 'agreement among raft nodes before linearized reading' (duration: 422.799578ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:12.690937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:12.26806Z","time spent":"422.872779ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:03:15.572792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.693666ms","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-01-30T16:03:15.572872Z","caller":"traceutil/trace.go:171","msg":"trace[889070068] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:605; }","duration":"220.785678ms","start":"2026-01-30T16:03:15.352048Z","end":"2026-01-30T16:03:15.572834Z","steps":["trace[889070068] 'range keys from in-memory index tree' (duration: 220.620254ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:21.886167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.773745ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-30T16:03:27.879453Z","caller":"traceutil/trace.go:171","msg":"trace[1458590896] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"250.565321ms","start":"2026-01-30T16:03:27.628872Z","end":"2026-01-30T16:03:27.879437Z","steps":["trace[1458590896] 'process raft request' (duration: 250.464499ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:29.032491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058380185s","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-01-30T16:03:29.032542Z","caller":"traceutil/trace.go:171","msg":"trace[813129555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"1.058437706s","start":"2026-01-30T16:03:27.974092Z","end":"2026-01-30T16:03:29.032529Z","steps":["trace[813129555] 'range keys from in-memory index tree' (duration: 1.058261233s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:29.032573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:27.974013Z","time spent":"1.058554039s","remote":"127.0.0.1:48082","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-01-30T16:03:29.032663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027023417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:29.032685Z","caller":"traceutil/trace.go:171","msg":"trace[697858625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"1.027071627s","start":"2026-01-30T16:03:28.005607Z","end":"2026-01-30T16:03:29.032679Z","steps":["trace[697858625] 'range keys from in-memory index tree' (duration: 1.026978896s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:29.032701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:28.005594Z","time spent":"1.027103539s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:03:29.033348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.210267ms","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-01-30T16:03:29.033387Z","caller":"traceutil/trace.go:171","msg":"trace[824380786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:713; }","duration":"247.277079ms","start":"2026-01-30T16:03:28.786101Z","end":"2026-01-30T16:03:29.033378Z","steps":["trace[824380786] 'range keys from in-memory index tree' (duration: 247.107875ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:29.033616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.509974ms","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-01-30T16:03:29.03365Z","caller":"traceutil/trace.go:171","msg":"trace[629137824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:713; }","duration":"238.572795ms","start":"2026-01-30T16:03:28.795069Z","end":"2026-01-30T16:03:29.033642Z","steps":["trace[629137824] 'range keys from in-memory index tree' (duration: 238.442922ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:29.550855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415084072921,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:03:29.871411Z","caller":"traceutil/trace.go:171","msg":"trace[857324328] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"829.439083ms","start":"2026-01-30T16:03:29.041921Z","end":"2026-01-30T16:03:29.87136Z","steps":["trace[857324328] 'process raft request' (duration: 829.29358ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:29.871533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:29.04191Z","time spent":"829.567327ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:30.109185Z","caller":"traceutil/trace.go:171","msg":"trace[1134787488] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:739; }","duration":"1.059093219s","start":"2026-01-30T16:03:29.050075Z","end":"2026-01-30T16:03:30.109168Z","steps":["trace[1134787488] 'read index received' (duration: 821.790203ms)","trace[1134787488] 'applied index is now lower than readState.Index' (duration: 237.302396ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:03:30.109203Z","caller":"traceutil/trace.go:171","msg":"trace[281855959] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"1.065404499s","start":"2026-01-30T16:03:29.043772Z","end":"2026-01-30T16:03:30.109176Z","steps":["trace[281855959] 'process raft request' (duration: 1.057547554s)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:30.109259Z","caller":"traceutil/trace.go:171","msg":"trace[1836373722] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.060971201s","start":"2026-01-30T16:03:29.048281Z","end":"2026-01-30T16:03:30.109252Z","steps":["trace[1836373722] 'process raft request' (duration: 1.060829408s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:30.10933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:29.043758Z","time spent":"1.065503541s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:03:30.109348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:29.048275Z","time spent":"1.061007581s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:30.109438Z","caller":"traceutil/trace.go:171","msg":"trace[1489190263] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"1.061118394s","start":"2026-01-30T16:03:29.048312Z","end":"2026-01-30T16:03:30.10943Z","steps":["trace[1489190263] 'process raft request' (duration: 1.060827827s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:30.109465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:29.048309Z","time spent":"1.061141344s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:03:30.109572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059500618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:30.109594Z","caller":"traceutil/trace.go:171","msg":"trace[105147709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"1.060279435s","start":"2026-01-30T16:03:29.049306Z","end":"2026-01-30T16:03:30.109586Z","steps":["trace[105147709] 'agreement among raft nodes before linearized reading' (duration: 1.060197833s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:30.109613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:29.0493Z","time spent":"1.060308636s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:03:30.112079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010999078s","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-01-30T16:03:30.112103Z","caller":"traceutil/trace.go:171","msg":"trace[53121271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"1.011047408s","start":"2026-01-30T16:03:29.101049Z","end":"2026-01-30T16:03:30.112097Z","steps":["trace[53121271] 'agreement among raft nodes before linearized reading' (duration: 1.010920705s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:30.112119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:29.101037Z","time spent":"1.011078099s","remote":"127.0.0.1:48082","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":"info","ts":"2026-01-30T16:03:30.11223Z","caller":"traceutil/trace.go:171","msg":"trace[933505805] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"113.345625ms","start":"2026-01-30T16:03:29.998868Z","end":"2026-01-30T16:03:30.112214Z","steps":["trace[933505805] 'process raft request' (duration: 113.049018ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:33.467689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354619231s","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-01-30T16:03:33.467749Z","caller":"traceutil/trace.go:171","msg":"trace[1306162434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"1.354708934s","start":"2026-01-30T16:03:32.113024Z","end":"2026-01-30T16:03:33.467733Z","steps":["trace[1306162434] 'range keys from in-memory index tree' (duration: 1.354510879s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:33.467775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:32.113011Z","time spent":"1.354758915s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:03:33.467919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354629262s","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-01-30T16:03:33.467936Z","caller":"traceutil/trace.go:171","msg":"trace[498462004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:720; }","duration":"1.354653762s","start":"2026-01-30T16:03:32.113277Z","end":"2026-01-30T16:03:33.46793Z","steps":["trace[498462004] 'range keys from in-memory index tree' (duration: 1.35458619s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:33.467953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:32.113273Z","time spent":"1.354677093s","remote":"127.0.0.1:48082","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-01-30T16:03:33.46805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35229865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-30T16:03:33.468069Z","caller":"traceutil/trace.go:171","msg":"trace[1743174658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"1.35232572s","start":"2026-01-30T16:03:32.115737Z","end":"2026-01-30T16:03:33.468063Z","steps":["trace[1743174658] 'range keys from in-memory index tree' (duration: 1.352258929s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:33.468086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:32.115732Z","time spent":"1.352348692s","remote":"127.0.0.1:48082","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-01-30T16:03:33.468203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330876412s","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-01-30T16:03:33.468223Z","caller":"traceutil/trace.go:171","msg":"trace[1456257571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"1.330916614s","start":"2026-01-30T16:03:32.137301Z","end":"2026-01-30T16:03:33.468218Z","steps":["trace[1456257571] 'range keys from in-memory index tree' (duration: 1.330811082s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:33.468248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:32.13729Z","time spent":"1.330954414s","remote":"127.0.0.1:48082","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-01-30T16:03:33.468536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.26795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:33.468555Z","caller":"traceutil/trace.go:171","msg":"trace[1807235741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"464.322201ms","start":"2026-01-30T16:03:33.004227Z","end":"2026-01-30T16:03:33.468549Z","steps":["trace[1807235741] 'range keys from in-memory index tree' (duration: 464.213579ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:33.46857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:33.00421Z","time spent":"464.356221ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T16:03:33.890973Z","caller":"traceutil/trace.go:171","msg":"trace[1158591786] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"413.147281ms","start":"2026-01-30T16:03:33.477803Z","end":"2026-01-30T16:03:33.89095Z","steps":["trace[1158591786] 'process raft request' (duration: 413.021858ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:33.891128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:33.477787Z","time spent":"413.269525ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:34.057366Z","caller":"traceutil/trace.go:171","msg":"trace[1574603616] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:747; }","duration":"120.451753ms","start":"2026-01-30T16:03:33.936899Z","end":"2026-01-30T16:03:34.05735Z","steps":["trace[1574603616] 'read index received' (duration: 120.281919ms)","trace[1574603616] 'applied index is now lower than readState.Index' (duration: 169.224µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:34.057531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.617298ms","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-01-30T16:03:34.057564Z","caller":"traceutil/trace.go:171","msg":"trace[736281352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:724; }","duration":"120.71857ms","start":"2026-01-30T16:03:33.936836Z","end":"2026-01-30T16:03:34.057554Z","steps":["trace[736281352] 'agreement among raft nodes before linearized reading' (duration: 120.578797ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:34.057799Z","caller":"traceutil/trace.go:171","msg":"trace[1355019146] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"579.867245ms","start":"2026-01-30T16:03:33.477923Z","end":"2026-01-30T16:03:34.05779Z","steps":["trace[1355019146] 'process raft request' (duration: 579.297222ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:34.057861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:33.477917Z","time spent":"579.900197ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:34.057945Z","caller":"traceutil/trace.go:171","msg":"trace[1769387055] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"579.981858ms","start":"2026-01-30T16:03:33.477956Z","end":"2026-01-30T16:03:34.057938Z","steps":["trace[1769387055] 'process raft request' (duration: 579.346144ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:34.057978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:33.477952Z","time spent":"580.006299ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:34.058277Z","caller":"traceutil/trace.go:171","msg":"trace[956896372] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"579.003047ms","start":"2026-01-30T16:03:33.479264Z","end":"2026-01-30T16:03:34.058267Z","steps":["trace[956896372] 'process raft request' (duration: 578.066116ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:34.058355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:33.479249Z","time spent":"579.047847ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:35.400399Z","caller":"traceutil/trace.go:171","msg":"trace[912722439] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"123.322527ms","start":"2026-01-30T16:03:35.277056Z","end":"2026-01-30T16:03:35.400379Z","steps":["trace[912722439] 'process raft request' (duration: 123.208655ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:35.604994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.560422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-01-30T16:03:35.60506Z","caller":"traceutil/trace.go:171","msg":"trace[1233925678] transaction","detail":"{read_only:false; number_of_response:1; response_revision:730; }","duration":"201.634841ms","start":"2026-01-30T16:03:35.403417Z","end":"2026-01-30T16:03:35.605052Z","steps":["trace[1233925678] 'compare' (duration: 176.485361ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:39.542288Z","caller":"traceutil/trace.go:171","msg":"trace[77113507] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"117.064478ms","start":"2026-01-30T16:03:39.425206Z","end":"2026-01-30T16:03:39.54227Z","steps":["trace[77113507] 'process raft request' (duration: 116.793372ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:40.474389Z","caller":"traceutil/trace.go:171","msg":"trace[2138874512] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"358.363051ms","start":"2026-01-30T16:03:40.116009Z","end":"2026-01-30T16:03:40.474372Z","steps":["trace[2138874512] 'read index received' (duration: 358.262819ms)","trace[2138874512] 'applied index is now lower than readState.Index' (duration: 99.612µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:03:40.474456Z","caller":"traceutil/trace.go:171","msg":"trace[2095362310] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"394.246881ms","start":"2026-01-30T16:03:40.080202Z","end":"2026-01-30T16:03:40.474449Z","steps":["trace[2095362310] 'process raft request' (duration: 394.023786ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:40.474521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:40.080193Z","time spent":"394.278042ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:03:40.474675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.649798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T16:03:40.474732Z","caller":"traceutil/trace.go:171","msg":"trace[1032254251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:745; }","duration":"358.709619ms","start":"2026-01-30T16:03:40.116004Z","end":"2026-01-30T16:03:40.474714Z","steps":["trace[1032254251] 'agreement among raft nodes before linearized reading' (duration: 358.567596ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:40.474765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:40.115958Z","time spent":"358.799581ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-30T16:03:40.481523Z","caller":"traceutil/trace.go:171","msg":"trace[1782491794] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"298.560769ms","start":"2026-01-30T16:03:40.182952Z","end":"2026-01-30T16:03:40.481513Z","steps":["trace[1782491794] 'process raft request' (duration: 298.498838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:40.481836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.735915ms","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-01-30T16:03:40.48186Z","caller":"traceutil/trace.go:171","msg":"trace[2063968300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"365.775866ms","start":"2026-01-30T16:03:40.116076Z","end":"2026-01-30T16:03:40.481852Z","steps":["trace[2063968300] 'agreement among raft nodes before linearized reading' (duration: 365.698844ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:40.481878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:40.116071Z","time spent":"365.802746ms","remote":"127.0.0.1:48082","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-01-30T16:03:40.482091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.202203ms","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-01-30T16:03:40.482109Z","caller":"traceutil/trace.go:171","msg":"trace[1277090315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"365.263044ms","start":"2026-01-30T16:03:40.116841Z","end":"2026-01-30T16:03:40.482104Z","steps":["trace[1277090315] 'agreement among raft nodes before linearized reading' (duration: 365.211963ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:40.482134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:40.11683Z","time spent":"365.300026ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T16:03:40.697186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.499304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:03:40.697344Z","caller":"traceutil/trace.go:171","msg":"trace[1494268648] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"211.255076ms","start":"2026-01-30T16:03:40.486072Z","end":"2026-01-30T16:03:40.697327Z","steps":["trace[1494268648] 'process raft request' (duration: 211.201155ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:40.697355Z","caller":"traceutil/trace.go:171","msg":"trace[1663005042] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"211.341668ms","start":"2026-01-30T16:03:40.485989Z","end":"2026-01-30T16:03:40.69733Z","steps":["trace[1663005042] 'process raft request' (duration: 55.617059ms)","trace[1663005042] 'compare' (duration: 155.382582ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:52.088814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.526343ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-30T16:03:52.089009Z","caller":"traceutil/trace.go:171","msg":"trace[1576722816] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"336.232049ms","start":"2026-01-30T16:03:51.752723Z","end":"2026-01-30T16:03:52.088955Z","steps":["trace[1576722816] 'read index received' (duration: 141.570303ms)","trace[1576722816] 'applied index is now lower than readState.Index' (duration: 194.660646ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:52.089179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.478695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:52.08987Z","caller":"traceutil/trace.go:171","msg":"trace[1568882040] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:777; }","duration":"337.255002ms","start":"2026-01-30T16:03:51.752606Z","end":"2026-01-30T16:03:52.089861Z","steps":["trace[1568882040] 'agreement among raft nodes before linearized reading' (duration: 336.505065ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:52.089984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:51.752574Z","time spent":"337.399036ms","remote":"127.0.0.1:47992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:03:52.089348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.139042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:52.090153Z","caller":"traceutil/trace.go:171","msg":"trace[109078699] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:777; }","duration":"299.004993ms","start":"2026-01-30T16:03:51.79114Z","end":"2026-01-30T16:03:52.090145Z","steps":["trace[109078699] 'agreement among raft nodes before linearized reading' (duration: 298.163753ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:52.089791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.175393ms","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-01-30T16:03:52.090584Z","caller":"traceutil/trace.go:171","msg":"trace[1181704707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"129.981942ms","start":"2026-01-30T16:03:51.960572Z","end":"2026-01-30T16:03:52.090554Z","steps":["trace[1181704707] 'agreement among raft nodes before linearized reading' (duration: 129.148033ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:52.733723Z","caller":"traceutil/trace.go:171","msg":"trace[560036233] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"151.203156ms","start":"2026-01-30T16:03:52.582498Z","end":"2026-01-30T16:03:52.733701Z","steps":["trace[560036233] 'read index received' (duration: 150.924679ms)","trace[560036233] 'applied index is now lower than readState.Index' (duration: 277.727µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:03:52.734185Z","caller":"traceutil/trace.go:171","msg":"trace[168566732] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"177.027777ms","start":"2026-01-30T16:03:52.557143Z","end":"2026-01-30T16:03:52.734171Z","steps":["trace[168566732] 'process raft request' (duration: 176.435873ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:52.734319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.8133ms","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-01-30T16:03:52.737065Z","caller":"traceutil/trace.go:171","msg":"trace[1582155867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"154.562584ms","start":"2026-01-30T16:03:52.582461Z","end":"2026-01-30T16:03:52.737023Z","steps":["trace[1582155867] 'agreement among raft nodes before linearized reading' (duration: 151.759959ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:53.21819Z","caller":"traceutil/trace.go:171","msg":"trace[1585511411] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"387.577983ms","start":"2026-01-30T16:03:52.830581Z","end":"2026-01-30T16:03:53.218159Z","steps":["trace[1585511411] 'process raft request' (duration: 290.26003ms)","trace[1585511411] 'compare' (duration: 96.939364ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:53.218346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:52.830567Z","time spent":"387.693645ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:53.222707Z","caller":"traceutil/trace.go:171","msg":"trace[680823100] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:810; }","duration":"218.4651ms","start":"2026-01-30T16:03:53.004118Z","end":"2026-01-30T16:03:53.222583Z","steps":["trace[680823100] 'read index received' (duration: 116.757885ms)","trace[680823100] 'applied index is now lower than readState.Index' (duration: 101.706335ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:53.222944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.804858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:53.222769Z","caller":"traceutil/trace.go:171","msg":"trace[1748701289] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"392.141399ms","start":"2026-01-30T16:03:52.830616Z","end":"2026-01-30T16:03:53.222757Z","steps":["trace[1748701289] 'process raft request' (duration: 391.870723ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:53.223312Z","caller":"traceutil/trace.go:171","msg":"trace[646497916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"219.122485ms","start":"2026-01-30T16:03:53.004079Z","end":"2026-01-30T16:03:53.223201Z","steps":["trace[646497916] 'agreement among raft nodes before linearized reading' (duration: 218.800088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:53.223527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:52.8306Z","time spent":"392.658302ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:54.689928Z","caller":"traceutil/trace.go:171","msg":"trace[1205492197] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"572.192434ms","start":"2026-01-30T16:03:54.117648Z","end":"2026-01-30T16:03:54.689841Z","steps":["trace[1205492197] 'process raft request' (duration: 572.080062ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:54.690145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:54.117632Z","time spent":"572.44654ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:03:55.331833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415084073120,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:03:55.401836Z","caller":"traceutil/trace.go:171","msg":"trace[299844197] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"570.633909ms","start":"2026-01-30T16:03:54.831179Z","end":"2026-01-30T16:03:55.401813Z","steps":["trace[299844197] 'read index received' (duration: 527.24744ms)","trace[299844197] 'applied index is now lower than readState.Index' (duration: 43.385489ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:03:55.401991Z","caller":"traceutil/trace.go:171","msg":"trace[1309572018] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"659.743072ms","start":"2026-01-30T16:03:54.74222Z","end":"2026-01-30T16:03:55.401963Z","steps":["trace[1309572018] 'process raft request' (duration: 616.261571ms)","trace[1309572018] 'compare' (duration: 43.197594ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:55.402122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.923656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-01-30T16:03:55.402116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:54.742203Z","time spent":"659.845154ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:55.402158Z","caller":"traceutil/trace.go:171","msg":"trace[2081411054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"570.999358ms","start":"2026-01-30T16:03:54.831144Z","end":"2026-01-30T16:03:55.402144Z","steps":["trace[2081411054] 'agreement among raft nodes before linearized reading' (duration: 570.845124ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:55.40219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:54.831124Z","time spent":"571.05827ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:03:55.402315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.33743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:55.402348Z","caller":"traceutil/trace.go:171","msg":"trace[1061208726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"397.503524ms","start":"2026-01-30T16:03:55.004834Z","end":"2026-01-30T16:03:55.402338Z","steps":["trace[1061208726] 'agreement among raft nodes before linearized reading' (duration: 397.455282ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:55.402372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:55.004816Z","time spent":"397.551365ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:03:55.403761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.396271ms","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-01-30T16:03:55.403807Z","caller":"traceutil/trace.go:171","msg":"trace[1860538554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"176.465753ms","start":"2026-01-30T16:03:55.227329Z","end":"2026-01-30T16:03:55.403795Z","steps":["trace[1860538554] 'agreement among raft nodes before linearized reading' (duration: 175.128572ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:55.404475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.851339ms","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-01-30T16:03:55.404515Z","caller":"traceutil/trace.go:171","msg":"trace[2011049287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"181.947151ms","start":"2026-01-30T16:03:55.222558Z","end":"2026-01-30T16:03:55.404505Z","steps":["trace[2011049287] 'agreement among raft nodes before linearized reading' (duration: 181.46963ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:03:55.584631Z","caller":"traceutil/trace.go:171","msg":"trace[43263384] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"174.522308ms","start":"2026-01-30T16:03:55.409745Z","end":"2026-01-30T16:03:55.584267Z","steps":["trace[43263384] 'process raft request' (duration: 144.388808ms)","trace[43263384] 'compare' (duration: 29.623578ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:55.844509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.363309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:03:55.844666Z","caller":"traceutil/trace.go:171","msg":"trace[1595221658] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:814; }","duration":"369.206776ms","start":"2026-01-30T16:03:55.475444Z","end":"2026-01-30T16:03:55.844651Z","steps":["trace[1595221658] 'read index received' (duration: 78.696261ms)","trace[1595221658] 'applied index is now lower than readState.Index' (duration: 290.498215ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:55.844757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.330128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:03:55.844788Z","caller":"traceutil/trace.go:171","msg":"trace[830068374] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:787; }","duration":"369.366759ms","start":"2026-01-30T16:03:55.475414Z","end":"2026-01-30T16:03:55.84478Z","steps":["trace[830068374] 'agreement among raft nodes before linearized reading' (duration: 369.288877ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:55.844833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:55.475382Z","time spent":"369.440151ms","remote":"127.0.0.1:48102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-01-30T16:03:55.845146Z","caller":"traceutil/trace.go:171","msg":"trace[1450644002] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"433.951571ms","start":"2026-01-30T16:03:55.411182Z","end":"2026-01-30T16:03:55.845133Z","steps":["trace[1450644002] 'process raft request' (duration: 306.895807ms)","trace[1450644002] 'compare' (duration: 126.276696ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:03:55.845212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:55.411136Z","time spent":"434.043003ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:03:55.845328Z","caller":"traceutil/trace.go:171","msg":"trace[628107465] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"432.730823ms","start":"2026-01-30T16:03:55.412586Z","end":"2026-01-30T16:03:55.845317Z","steps":["trace[628107465] 'process raft request' (duration: 432.007736ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:03:55.845382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:03:55.412578Z","time spent":"432.777623ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:08.504802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415084073320,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:04:08.538492Z","caller":"traceutil/trace.go:171","msg":"trace[1901073299] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"590.03543ms","start":"2026-01-30T16:04:07.948442Z","end":"2026-01-30T16:04:08.538477Z","steps":["trace[1901073299] 'process raft request' (duration: 589.873607ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:08.538616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:07.948432Z","time spent":"590.121562ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:09.971046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.539043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:04:09.971104Z","caller":"traceutil/trace.go:171","msg":"trace[1741347623] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"1.966881986s","start":"2026-01-30T16:04:08.00421Z","end":"2026-01-30T16:04:09.971092Z","steps":["trace[1741347623] 'read index received' (duration: 534.313504ms)","trace[1741347623] 'applied index is now lower than readState.Index' (duration: 1.432567892s)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:09.971218Z","caller":"traceutil/trace.go:171","msg":"trace[1822881430] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"2.022661133s","start":"2026-01-30T16:04:07.94855Z","end":"2026-01-30T16:04:09.971211Z","steps":["trace[1822881430] 'process raft request' (duration: 1.188912675s)","trace[1822881430] 'compare' (duration: 833.273586ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:09.971247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:07.948545Z","time spent":"2.022687394s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:09.971375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.967170032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:04:09.97139Z","caller":"traceutil/trace.go:171","msg":"trace[1148175180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:873; }","duration":"1.967217094s","start":"2026-01-30T16:04:08.004167Z","end":"2026-01-30T16:04:09.971385Z","steps":["trace[1148175180] 'agreement among raft nodes before linearized reading' (duration: 1.967179123s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:09.971406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:08.004157Z","time spent":"1.967244394s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:04:09.971487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662081668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T16:04:09.971499Z","caller":"traceutil/trace.go:171","msg":"trace[752184471] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:873; }","duration":"1.662127819s","start":"2026-01-30T16:04:08.309367Z","end":"2026-01-30T16:04:09.971495Z","steps":["trace[752184471] 'agreement among raft nodes before linearized reading' (duration: 1.662101199s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:09.971531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:08.309354Z","time spent":"1.6621539s","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:04:09.971692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.412687ms","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-01-30T16:04:09.971705Z","caller":"traceutil/trace.go:171","msg":"trace[905401020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:873; }","duration":"331.449007ms","start":"2026-01-30T16:04:09.640252Z","end":"2026-01-30T16:04:09.971701Z","steps":["trace[905401020] 'agreement among raft nodes before linearized reading' (duration: 331.391926ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:09.971719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:09.640239Z","time spent":"331.476107ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:04:09.971793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.196663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-30T16:04:09.971806Z","caller":"traceutil/trace.go:171","msg":"trace[722942074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:873; }","duration":"510.238454ms","start":"2026-01-30T16:04:09.461564Z","end":"2026-01-30T16:04:09.971802Z","steps":["trace[722942074] 'agreement among raft nodes before linearized reading' (duration: 510.203153ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:09.971816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:09.461549Z","time spent":"510.265395ms","remote":"127.0.0.1:48082","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-01-30T16:04:09.971889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141010851s","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-01-30T16:04:09.9719Z","caller":"traceutil/trace.go:171","msg":"trace[1134780368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:873; }","duration":"1.141023871s","start":"2026-01-30T16:04:08.830873Z","end":"2026-01-30T16:04:09.971897Z","steps":["trace[1134780368] 'agreement among raft nodes before linearized reading' (duration: 1.140987031s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:09.971912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:08.83086Z","time spent":"1.141049383s","remote":"127.0.0.1:48082","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-01-30T16:04:10.483911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415084073325,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:04:10.671806Z","caller":"traceutil/trace.go:171","msg":"trace[1900370269] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"697.163082ms","start":"2026-01-30T16:04:09.974627Z","end":"2026-01-30T16:04:10.67179Z","steps":["trace[1900370269] 'process raft request' (duration: 696.938747ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:10.671918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:09.974618Z","time spent":"697.235463ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:10.718393Z","caller":"traceutil/trace.go:171","msg":"trace[2073999001] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:908; }","duration":"734.614913ms","start":"2026-01-30T16:04:09.983757Z","end":"2026-01-30T16:04:10.718372Z","steps":["trace[2073999001] 'read index received' (duration: 687.871796ms)","trace[2073999001] 'applied index is now lower than readState.Index' (duration: 46.741977ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:10.718678Z","caller":"traceutil/trace.go:171","msg":"trace[725346132] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"743.475658ms","start":"2026-01-30T16:04:09.975189Z","end":"2026-01-30T16:04:10.718664Z","steps":["trace[725346132] 'process raft request' (duration: 743.021938ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:10.718783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:09.975182Z","time spent":"743.53041ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:10.718981Z","caller":"traceutil/trace.go:171","msg":"trace[1171622958] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"742.786653ms","start":"2026-01-30T16:04:09.976184Z","end":"2026-01-30T16:04:10.718971Z","steps":["trace[1171622958] 'process raft request' (duration: 742.153248ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:10.719057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:09.976178Z","time spent":"742.832484ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:10.719202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.439482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:04:10.719239Z","caller":"traceutil/trace.go:171","msg":"trace[1872412363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"735.490633ms","start":"2026-01-30T16:04:09.983739Z","end":"2026-01-30T16:04:10.71923Z","steps":["trace[1872412363] 'agreement among raft nodes before linearized reading' (duration: 735.437541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:10.719267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:09.983733Z","time spent":"735.527443ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:04:10.719457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.235505ms","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-01-30T16:04:10.719489Z","caller":"traceutil/trace.go:171","msg":"trace[969347443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:876; }","duration":"178.303836ms","start":"2026-01-30T16:04:10.541177Z","end":"2026-01-30T16:04:10.719481Z","steps":["trace[969347443] 'agreement among raft nodes before linearized reading' (duration: 178.206074ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:12.113202Z","caller":"traceutil/trace.go:171","msg":"trace[659074847] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"131.885807ms","start":"2026-01-30T16:04:11.981283Z","end":"2026-01-30T16:04:12.113169Z","steps":["trace[659074847] 'process raft request' (duration: 131.735424ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:13.00269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.968453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:04:13.003055Z","caller":"traceutil/trace.go:171","msg":"trace[368589540] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"221.755887ms","start":"2026-01-30T16:04:12.781286Z","end":"2026-01-30T16:04:13.003042Z","steps":["trace[368589540] 'process raft request' (duration: 103.379264ms)","trace[368589540] 'compare' (duration: 117.84198ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:13.003139Z","caller":"traceutil/trace.go:171","msg":"trace[411091000] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"221.231475ms","start":"2026-01-30T16:04:12.781901Z","end":"2026-01-30T16:04:13.003133Z","steps":["trace[411091000] 'process raft request' (duration: 220.865046ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:13.003235Z","caller":"traceutil/trace.go:171","msg":"trace[226094671] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"220.306593ms","start":"2026-01-30T16:04:12.782924Z","end":"2026-01-30T16:04:13.00323Z","steps":["trace[226094671] 'process raft request' (duration: 219.873203ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:14.336571Z","caller":"traceutil/trace.go:171","msg":"trace[66045616] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"135.702906ms","start":"2026-01-30T16:04:14.200846Z","end":"2026-01-30T16:04:14.336549Z","steps":["trace[66045616] 'process raft request' (duration: 135.587353ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:18.816914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.980515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:04:18.816962Z","caller":"traceutil/trace.go:171","msg":"trace[1955462003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"816.059426ms","start":"2026-01-30T16:04:18.000891Z","end":"2026-01-30T16:04:18.81695Z","steps":["trace[1955462003] 'range keys from in-memory index tree' (duration: 815.929584ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:18.816983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:18.000877Z","time spent":"816.101208ms","remote":"127.0.0.1:47868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:04:18.817305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.617281ms","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-01-30T16:04:18.81732Z","caller":"traceutil/trace.go:171","msg":"trace[701483243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"460.654452ms","start":"2026-01-30T16:04:18.356661Z","end":"2026-01-30T16:04:18.817316Z","steps":["trace[701483243] 'range keys from in-memory index tree' (duration: 460.533809ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:18.817332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:18.356648Z","time spent":"460.680862ms","remote":"127.0.0.1:48082","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-01-30T16:04:19.346156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415084073386,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:04:19.421646Z","caller":"traceutil/trace.go:171","msg":"trace[550959086] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"575.898392ms","start":"2026-01-30T16:04:18.845733Z","end":"2026-01-30T16:04:19.421632Z","steps":["trace[550959086] 'read index received' (duration: 575.770629ms)","trace[550959086] 'applied index is now lower than readState.Index' (duration: 127.193µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:19.421848Z","caller":"traceutil/trace.go:171","msg":"trace[459870231] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"600.640417ms","start":"2026-01-30T16:04:18.821203Z","end":"2026-01-30T16:04:19.421843Z","steps":["trace[459870231] 'process raft request' (duration: 600.34652ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:19.421941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:18.82119Z","time spent":"600.674517ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:19.42208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.347072ms","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-01-30T16:04:19.422096Z","caller":"traceutil/trace.go:171","msg":"trace[1612004515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"576.389773ms","start":"2026-01-30T16:04:18.845701Z","end":"2026-01-30T16:04:19.422091Z","steps":["trace[1612004515] 'agreement among raft nodes before linearized reading' (duration: 576.331411ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:19.422109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:18.845686Z","time spent":"576.420684ms","remote":"127.0.0.1:48082","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-01-30T16:04:19.422169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.717476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:04:19.42218Z","caller":"traceutil/trace.go:171","msg":"trace[1536030763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"421.749957ms","start":"2026-01-30T16:04:19.000426Z","end":"2026-01-30T16:04:19.422176Z","steps":["trace[1536030763] 'agreement among raft nodes before linearized reading' (duration: 421.733086ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:19.422191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:19.000413Z","time spent":"421.775128ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:04:19.422313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.630717ms","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-01-30T16:04:19.422326Z","caller":"traceutil/trace.go:171","msg":"trace[70836875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"388.657228ms","start":"2026-01-30T16:04:19.033665Z","end":"2026-01-30T16:04:19.422322Z","steps":["trace[70836875] 'agreement among raft nodes before linearized reading' (duration: 388.621547ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:19.422338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:19.033655Z","time spent":"388.680658ms","remote":"127.0.0.1:48082","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-01-30T16:04:19.422406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.008072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T16:04:19.422417Z","caller":"traceutil/trace.go:171","msg":"trace[2022137419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"397.026372ms","start":"2026-01-30T16:04:19.025387Z","end":"2026-01-30T16:04:19.422414Z","steps":["trace[2022137419] 'agreement among raft nodes before linearized reading' (duration: 396.995271ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:19.422429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:19.025384Z","time spent":"397.041162ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:04:19.637834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.605605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-01-30T16:04:19.637874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.478863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:04:19.637906Z","caller":"traceutil/trace.go:171","msg":"trace[1114765848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"612.714387ms","start":"2026-01-30T16:04:19.025171Z","end":"2026-01-30T16:04:19.637885Z","steps":["trace[1114765848] 'agreement among raft nodes before linearized reading' (duration: 397.994575ms)","trace[1114765848] 'range keys from in-memory index tree' (duration: 214.537118ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:19.637916Z","caller":"traceutil/trace.go:171","msg":"trace[1450519293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"213.531904ms","start":"2026-01-30T16:04:19.424372Z","end":"2026-01-30T16:04:19.637904Z","steps":["trace[1450519293] 'range keys from in-memory index tree' (duration: 213.447513ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:19.637946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:19.025159Z","time spent":"612.778668ms","remote":"127.0.0.1:48082","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":"info","ts":"2026-01-30T16:04:19.743037Z","caller":"traceutil/trace.go:171","msg":"trace[1010291221] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"317.084323ms","start":"2026-01-30T16:04:19.425937Z","end":"2026-01-30T16:04:19.743021Z","steps":["trace[1010291221] 'process raft request' (duration: 316.98926ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:19.743299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:19.425928Z","time spent":"317.320018ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:20.065552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.220368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:04:20.065846Z","caller":"traceutil/trace.go:171","msg":"trace[1689812204] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"638.741732ms","start":"2026-01-30T16:04:19.427092Z","end":"2026-01-30T16:04:20.065834Z","steps":["trace[1689812204] 'process raft request' (duration: 511.191296ms)","trace[1689812204] 'compare' (duration: 126.988183ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:20.065883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:19.427085Z","time spent":"638.782073ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:20.065948Z","caller":"traceutil/trace.go:171","msg":"trace[54671816] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"637.76093ms","start":"2026-01-30T16:04:19.428182Z","end":"2026-01-30T16:04:20.065943Z","steps":["trace[54671816] 'process raft request' (duration: 637.424182ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:20.065969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:19.428176Z","time spent":"637.78091ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:20.066021Z","caller":"traceutil/trace.go:171","msg":"trace[1855868000] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"424.302176ms","start":"2026-01-30T16:04:19.641715Z","end":"2026-01-30T16:04:20.066017Z","steps":["trace[1855868000] 'process raft request' (duration: 423.921387ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:20.066043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:19.641696Z","time spent":"424.334378ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:21.488603Z","caller":"traceutil/trace.go:171","msg":"trace[2147292874] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"169.129053ms","start":"2026-01-30T16:04:21.319454Z","end":"2026-01-30T16:04:21.488583Z","steps":["trace[2147292874] 'process raft request' (duration: 168.655692ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:22.068943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.185833ms","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-01-30T16:04:22.068994Z","caller":"traceutil/trace.go:171","msg":"trace[418243053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"307.269065ms","start":"2026-01-30T16:04:21.761715Z","end":"2026-01-30T16:04:22.068984Z","steps":["trace[418243053] 'range keys from in-memory index tree' (duration: 307.092901ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:22.069014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:21.761701Z","time spent":"307.309106ms","remote":"127.0.0.1:48082","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-01-30T16:04:24.596237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415084073417,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:04:24.623423Z","caller":"traceutil/trace.go:171","msg":"trace[1594191668] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"527.852894ms","start":"2026-01-30T16:04:24.095556Z","end":"2026-01-30T16:04:24.623409Z","steps":["trace[1594191668] 'read index received' (duration: 527.726551ms)","trace[1594191668] 'applied index is now lower than readState.Index' (duration: 125.833µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:24.623589Z","caller":"traceutil/trace.go:171","msg":"trace[1571968251] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"541.065692ms","start":"2026-01-30T16:04:24.082518Z","end":"2026-01-30T16:04:24.623583Z","steps":["trace[1571968251] 'process raft request' (duration: 540.809836ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:24.623658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:24.082509Z","time spent":"541.095553ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:24.623794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.235703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T16:04:24.62381Z","caller":"traceutil/trace.go:171","msg":"trace[1618768538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"528.260104ms","start":"2026-01-30T16:04:24.095544Z","end":"2026-01-30T16:04:24.623804Z","steps":["trace[1618768538] 'agreement among raft nodes before linearized reading' (duration: 528.198053ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:24.623823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:24.095538Z","time spent":"528.282615ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:04:24.62397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.438452ms","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-01-30T16:04:24.623993Z","caller":"traceutil/trace.go:171","msg":"trace[456386136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"526.481382ms","start":"2026-01-30T16:04:24.097499Z","end":"2026-01-30T16:04:24.623981Z","steps":["trace[456386136] 'agreement among raft nodes before linearized reading' (duration: 526.439931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:24.624006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:24.097474Z","time spent":"526.529534ms","remote":"127.0.0.1:48082","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-01-30T16:04:24.624112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.594866ms","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-01-30T16:04:24.624131Z","caller":"traceutil/trace.go:171","msg":"trace[1742146492] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:908; }","duration":"102.638307ms","start":"2026-01-30T16:04:24.521488Z","end":"2026-01-30T16:04:24.624127Z","steps":["trace[1742146492] 'agreement among raft nodes before linearized reading' (duration: 102.606436ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:24.624242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.727922ms","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-01-30T16:04:24.624259Z","caller":"traceutil/trace.go:171","msg":"trace[308059059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"518.755333ms","start":"2026-01-30T16:04:24.1055Z","end":"2026-01-30T16:04:24.624256Z","steps":["trace[308059059] 'agreement among raft nodes before linearized reading' (duration: 518.713552ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:24.624271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:24.105495Z","time spent":"518.773393ms","remote":"127.0.0.1:48082","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-01-30T16:04:25.506389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132415084073420,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T16:04:25.784239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:25.416914Z","time spent":"367.314271ms","remote":"127.0.0.1:60142","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-30T16:04:25.831898Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.204811106s","expected-duration":"1s"} {"level":"info","ts":"2026-01-30T16:04:25.832458Z","caller":"traceutil/trace.go:171","msg":"trace[218827939] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"1.20539779s","start":"2026-01-30T16:04:24.62704Z","end":"2026-01-30T16:04:25.832438Z","steps":["trace[218827939] 'process raft request' (duration: 1.205270917s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:25.832629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:24.627028Z","time spent":"1.205488212s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:25.848745Z","caller":"traceutil/trace.go:171","msg":"trace[1441287652] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.221395192s","start":"2026-01-30T16:04:24.627331Z","end":"2026-01-30T16:04:25.848727Z","steps":["trace[1441287652] 'process raft request' (duration: 1.221261649s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:25.848895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:24.627326Z","time spent":"1.221466963s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:25.848912Z","caller":"traceutil/trace.go:171","msg":"trace[2040567385] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:947; }","duration":"843.393141ms","start":"2026-01-30T16:04:25.005476Z","end":"2026-01-30T16:04:25.848869Z","steps":["trace[2040567385] 'read index received' (duration: 827.593174ms)","trace[2040567385] 'applied index is now lower than readState.Index' (duration: 15.798987ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:25.849059Z","caller":"traceutil/trace.go:171","msg":"trace[267026443] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"1.22047473s","start":"2026-01-30T16:04:24.628574Z","end":"2026-01-30T16:04:25.849049Z","steps":["trace[267026443] 'process raft request' (duration: 1.220122182s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:25.849119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:24.628568Z","time spent":"1.220513781s","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:25.849183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.831618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T16:04:25.849211Z","caller":"traceutil/trace.go:171","msg":"trace[737754462] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:911; }","duration":"596.89371ms","start":"2026-01-30T16:04:25.25231Z","end":"2026-01-30T16:04:25.849204Z","steps":["trace[737754462] 'agreement among raft nodes before linearized reading' (duration: 596.847079ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:25.849233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:25.252298Z","time spent":"596.929921ms","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:04:25.849331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.862453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:04:25.849347Z","caller":"traceutil/trace.go:171","msg":"trace[30028120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"843.911104ms","start":"2026-01-30T16:04:25.005431Z","end":"2026-01-30T16:04:25.849342Z","steps":["trace[30028120] 'agreement among raft nodes before linearized reading' (duration: 843.886554ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:25.849371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:25.005409Z","time spent":"843.958645ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:04:25.849554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.461633ms","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-01-30T16:04:25.849591Z","caller":"traceutil/trace.go:171","msg":"trace[1720680358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"259.531885ms","start":"2026-01-30T16:04:25.590049Z","end":"2026-01-30T16:04:25.849581Z","steps":["trace[1720680358] 'agreement among raft nodes before linearized reading' (duration: 259.418862ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:26.059383Z","caller":"traceutil/trace.go:171","msg":"trace[619927260] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"201.209099ms","start":"2026-01-30T16:04:25.858155Z","end":"2026-01-30T16:04:26.059364Z","steps":["trace[619927260] 'process raft request' (duration: 102.822141ms)","trace[619927260] 'compare' (duration: 97.925487ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:26.79546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.917033ms","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-01-30T16:04:26.795623Z","caller":"traceutil/trace.go:171","msg":"trace[1625006797] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:912; }","duration":"386.094338ms","start":"2026-01-30T16:04:26.409512Z","end":"2026-01-30T16:04:26.795607Z","steps":["trace[1625006797] 'range keys from in-memory index tree' (duration: 385.901483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:26.795996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.833342ms","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-01-30T16:04:26.796061Z","caller":"traceutil/trace.go:171","msg":"trace[457569619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"167.932754ms","start":"2026-01-30T16:04:26.628111Z","end":"2026-01-30T16:04:26.796043Z","steps":["trace[457569619] 'range keys from in-memory index tree' (duration: 167.690259ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:26.796072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.818589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-30T16:04:26.796108Z","caller":"traceutil/trace.go:171","msg":"trace[102597232] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:912; }","duration":"357.877741ms","start":"2026-01-30T16:04:26.438219Z","end":"2026-01-30T16:04:26.796097Z","steps":["trace[102597232] 'range keys from in-memory index tree' (duration: 357.702937ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:26.79614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:26.438206Z","time spent":"357.926712ms","remote":"127.0.0.1:47852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"info","ts":"2026-01-30T16:04:26.965487Z","caller":"traceutil/trace.go:171","msg":"trace[740385496] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"136.075084ms","start":"2026-01-30T16:04:26.829391Z","end":"2026-01-30T16:04:26.965466Z","steps":["trace[740385496] 'process raft request' (duration: 135.92858ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:27.615052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.826288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:04:27.615163Z","caller":"traceutil/trace.go:171","msg":"trace[837576714] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"703.067529ms","start":"2026-01-30T16:04:26.912079Z","end":"2026-01-30T16:04:27.615146Z","steps":["trace[837576714] 'read index received' (duration: 53.908654ms)","trace[837576714] 'applied index is now lower than readState.Index' (duration: 649.157115ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:27.615282Z","caller":"traceutil/trace.go:171","msg":"trace[177214187] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"706.5491ms","start":"2026-01-30T16:04:26.90872Z","end":"2026-01-30T16:04:27.615269Z","steps":["trace[177214187] 'process raft request' (duration: 106.428365ms)","trace[177214187] 'compare' (duration: 599.186723ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:27.615345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:26.908697Z","time spent":"706.618262ms","remote":"127.0.0.1:47852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:27.615596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.510169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:04:27.615635Z","caller":"traceutil/trace.go:171","msg":"trace[208703641] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:914; }","duration":"703.579011ms","start":"2026-01-30T16:04:26.912046Z","end":"2026-01-30T16:04:27.615625Z","steps":["trace[208703641] 'agreement among raft nodes before linearized reading' (duration: 703.480549ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:27.615664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:26.912035Z","time spent":"703.622052ms","remote":"127.0.0.1:48078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:04:27.615836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.109234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:04:27.615871Z","caller":"traceutil/trace.go:171","msg":"trace[1084746530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"612.169034ms","start":"2026-01-30T16:04:27.003693Z","end":"2026-01-30T16:04:27.615862Z","steps":["trace[1084746530] 'agreement among raft nodes before linearized reading' (duration: 612.116563ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:27.6159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:27.003681Z","time spent":"612.210756ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:04:27.616118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.314689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T16:04:27.616174Z","caller":"traceutil/trace.go:171","msg":"trace[622691013] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:914; }","duration":"483.38831ms","start":"2026-01-30T16:04:27.132755Z","end":"2026-01-30T16:04:27.616144Z","steps":["trace[622691013] 'agreement among raft nodes before linearized reading' (duration: 483.334259ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:27.616202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:27.132734Z","time spent":"483.460182ms","remote":"127.0.0.1:48174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:04:28.194435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.525858ms","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-01-30T16:04:28.194505Z","caller":"traceutil/trace.go:171","msg":"trace[1547360230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"131.637101ms","start":"2026-01-30T16:04:28.06285Z","end":"2026-01-30T16:04:28.194487Z","steps":["trace[1547360230] 'range keys from in-memory index tree' (duration: 131.370025ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:28.194848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.776773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-30T16:04:28.19488Z","caller":"traceutil/trace.go:171","msg":"trace[880994966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:914; }","duration":"358.842794ms","start":"2026-01-30T16:04:27.83603Z","end":"2026-01-30T16:04:28.194873Z","steps":["trace[880994966] 'range keys from in-memory index tree' (duration: 358.64283ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:28.194902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:27.836009Z","time spent":"358.884605ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T16:04:28.195036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.779654ms","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-01-30T16:04:28.195055Z","caller":"traceutil/trace.go:171","msg":"trace[2084740772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"340.825025ms","start":"2026-01-30T16:04:27.854225Z","end":"2026-01-30T16:04:28.19505Z","steps":["trace[2084740772] 'range keys from in-memory index tree' (duration: 340.680202ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:28.195071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:27.85421Z","time spent":"340.856746ms","remote":"127.0.0.1:48082","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-01-30T16:04:28.195183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.571395ms","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-01-30T16:04:28.195199Z","caller":"traceutil/trace.go:171","msg":"trace[1381332240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"339.626837ms","start":"2026-01-30T16:04:27.855567Z","end":"2026-01-30T16:04:28.195194Z","steps":["trace[1381332240] 'range keys from in-memory index tree' (duration: 339.476304ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:28.195214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:27.855549Z","time spent":"339.661458ms","remote":"127.0.0.1:48082","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":"info","ts":"2026-01-30T16:04:28.461601Z","caller":"traceutil/trace.go:171","msg":"trace[1848650299] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"259.485803ms","start":"2026-01-30T16:04:28.202098Z","end":"2026-01-30T16:04:28.461584Z","steps":["trace[1848650299] 'process raft request' (duration: 259.388421ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:28.501986Z","caller":"traceutil/trace.go:171","msg":"trace[1742137677] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"298.944342ms","start":"2026-01-30T16:04:28.203025Z","end":"2026-01-30T16:04:28.501969Z","steps":["trace[1742137677] 'process raft request' (duration: 298.498731ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:28.502483Z","caller":"traceutil/trace.go:171","msg":"trace[1533371878] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:954; }","duration":"224.133942ms","start":"2026-01-30T16:04:28.278339Z","end":"2026-01-30T16:04:28.502473Z","steps":["trace[1533371878] 'read index received' (duration: 183.820315ms)","trace[1533371878] 'applied index is now lower than readState.Index' (duration: 40.312977ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:28.502658Z","caller":"traceutil/trace.go:171","msg":"trace[1205943657] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"299.559185ms","start":"2026-01-30T16:04:28.203091Z","end":"2026-01-30T16:04:28.50265Z","steps":["trace[1205943657] 'process raft request' (duration: 298.536922ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:28.50349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:28.203086Z","time spent":"300.365354ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:28.502711Z","caller":"traceutil/trace.go:171","msg":"trace[364379606] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"297.349094ms","start":"2026-01-30T16:04:28.205356Z","end":"2026-01-30T16:04:28.502705Z","steps":["trace[364379606] 'process raft request' (duration: 297.044307ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:28.502806Z","caller":"traceutil/trace.go:171","msg":"trace[1796131738] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"243.103583ms","start":"2026-01-30T16:04:28.259696Z","end":"2026-01-30T16:04:28.5028Z","steps":["trace[1796131738] 'process raft request' (duration: 242.737464ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:28.502865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.509481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-30T16:04:28.504153Z","caller":"traceutil/trace.go:171","msg":"trace[29861726] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:919; }","duration":"225.833991ms","start":"2026-01-30T16:04:28.27831Z","end":"2026-01-30T16:04:28.504144Z","steps":["trace[29861726] 'agreement among raft nodes before linearized reading' (duration: 224.51282ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:30.751576Z","caller":"traceutil/trace.go:171","msg":"trace[628894583] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"244.207149ms","start":"2026-01-30T16:04:30.507352Z","end":"2026-01-30T16:04:30.75156Z","steps":["trace[628894583] 'read index received' (duration: 244.067895ms)","trace[628894583] 'applied index is now lower than readState.Index' (duration: 138.584µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:30.752453Z","caller":"traceutil/trace.go:171","msg":"trace[1236943892] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"282.035428ms","start":"2026-01-30T16:04:30.470403Z","end":"2026-01-30T16:04:30.752438Z","steps":["trace[1236943892] 'process raft request' (duration: 281.064925ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:30.752787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.423427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-30T16:04:30.752821Z","caller":"traceutil/trace.go:171","msg":"trace[2096919709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"245.496239ms","start":"2026-01-30T16:04:30.507315Z","end":"2026-01-30T16:04:30.752811Z","steps":["trace[2096919709] 'agreement among raft nodes before linearized reading' (duration: 245.367376ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:30.753058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.511176ms","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-01-30T16:04:30.753086Z","caller":"traceutil/trace.go:171","msg":"trace[1251071095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"235.560498ms","start":"2026-01-30T16:04:30.517518Z","end":"2026-01-30T16:04:30.753078Z","steps":["trace[1251071095] 'agreement among raft nodes before linearized reading' (duration: 235.484436ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:30.753291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.688342ms","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-01-30T16:04:30.753317Z","caller":"traceutil/trace.go:171","msg":"trace[1578682412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"217.740653ms","start":"2026-01-30T16:04:30.535569Z","end":"2026-01-30T16:04:30.753309Z","steps":["trace[1578682412] 'agreement among raft nodes before linearized reading' (duration: 217.670172ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:31.677498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.040808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:04:31.677694Z","caller":"traceutil/trace.go:171","msg":"trace[285232312] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:960; }","duration":"703.289444ms","start":"2026-01-30T16:04:30.97439Z","end":"2026-01-30T16:04:31.67768Z","steps":["trace[285232312] 'read index received' (duration: 418.01557ms)","trace[285232312] 'applied index is now lower than readState.Index' (duration: 285.273094ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:31.677843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.453738ms","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-01-30T16:04:31.677871Z","caller":"traceutil/trace.go:171","msg":"trace[2136881744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"703.480318ms","start":"2026-01-30T16:04:30.97438Z","end":"2026-01-30T16:04:31.67786Z","steps":["trace[2136881744] 'agreement among raft nodes before linearized reading' (duration: 703.342415ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:31.677901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:30.974323Z","time spent":"703.57075ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-30T16:04:31.678148Z","caller":"traceutil/trace.go:171","msg":"trace[2087740776] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"919.601824ms","start":"2026-01-30T16:04:30.758531Z","end":"2026-01-30T16:04:31.678133Z","steps":["trace[2087740776] 'process raft request' (duration: 633.863889ms)","trace[2087740776] 'compare' (duration: 284.887665ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:31.678203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:30.758513Z","time spent":"919.659876ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:31.678324Z","caller":"traceutil/trace.go:171","msg":"trace[792175161] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"917.953746ms","start":"2026-01-30T16:04:30.76036Z","end":"2026-01-30T16:04:31.678314Z","steps":["trace[792175161] 'process raft request' (duration: 917.235889ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:31.678367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:30.760354Z","time spent":"917.989297ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:31.678478Z","caller":"traceutil/trace.go:171","msg":"trace[1666233361] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"917.207369ms","start":"2026-01-30T16:04:30.761263Z","end":"2026-01-30T16:04:31.67847Z","steps":["trace[1666233361] 'process raft request' (duration: 916.380719ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:31.678521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:30.761257Z","time spent":"917.240709ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:37.891641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.586896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:04:37.930719Z","caller":"traceutil/trace.go:171","msg":"trace[799460857] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"177.248711ms","start":"2026-01-30T16:04:37.714471Z","end":"2026-01-30T16:04:37.89172Z","steps":["trace[799460857] 'process raft request' (duration: 37.486941ms)","trace[799460857] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 139.428792ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:38.071076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.138813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:04:38.071206Z","caller":"traceutil/trace.go:171","msg":"trace[1886302326] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1006; }","duration":"354.630966ms","start":"2026-01-30T16:04:37.716546Z","end":"2026-01-30T16:04:38.071177Z","steps":["trace[1886302326] 'read index received' (duration: 35.366812ms)","trace[1886302326] 'applied index is now lower than readState.Index' (duration: 319.262574ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:04:38.071332Z","caller":"traceutil/trace.go:171","msg":"trace[2076692597] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"355.514466ms","start":"2026-01-30T16:04:37.715803Z","end":"2026-01-30T16:04:38.071317Z","steps":["trace[2076692597] 'process raft request' (duration: 216.999785ms)","trace[2076692597] 'compare' (duration: 138.01421ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:38.071393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:37.715795Z","time spent":"355.565868ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:04:38.071653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.100707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-30T16:04:38.071681Z","caller":"traceutil/trace.go:171","msg":"trace[1811643149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"355.140999ms","start":"2026-01-30T16:04:37.716529Z","end":"2026-01-30T16:04:38.07167Z","steps":["trace[1811643149] 'agreement among raft nodes before linearized reading' (duration: 354.997165ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:38.07171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:37.716522Z","time spent":"355.182529ms","remote":"127.0.0.1:48082","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-01-30T16:04:38.07216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.17328ms","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-01-30T16:04:38.072258Z","caller":"traceutil/trace.go:171","msg":"trace[1391777586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"303.311933ms","start":"2026-01-30T16:04:37.768904Z","end":"2026-01-30T16:04:38.072216Z","steps":["trace[1391777586] 'agreement among raft nodes before linearized reading' (duration: 302.975486ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:38.072328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:37.76889Z","time spent":"303.407625ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-30T16:04:44.241383Z","caller":"traceutil/trace.go:171","msg":"trace[1540910299] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"141.787507ms","start":"2026-01-30T16:04:44.099569Z","end":"2026-01-30T16:04:44.241356Z","steps":["trace[1540910299] 'process raft request' (duration: 136.553915ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:44.757053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.072563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:04:44.75738Z","caller":"traceutil/trace.go:171","msg":"trace[126213030] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"508.539025ms","start":"2026-01-30T16:04:44.248779Z","end":"2026-01-30T16:04:44.757318Z","steps":["trace[126213030] 'process raft request' (duration: 154.098123ms)","trace[126213030] 'compare' (duration: 296.856163ms)","trace[126213030] 'check requests' (duration: 57.015946ms)"],"step_count":3} {"level":"warn","ts":"2026-01-30T16:04:44.757484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:44.248747Z","time spent":"508.685817ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:44.758068Z","caller":"traceutil/trace.go:171","msg":"trace[1973669835] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1035; }","duration":"313.822527ms","start":"2026-01-30T16:04:44.444227Z","end":"2026-01-30T16:04:44.758049Z","steps":["trace[1973669835] 'read index received' (duration: 245.144779ms)","trace[1973669835] 'applied index is now lower than readState.Index' (duration: 68.674988ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:04:44.758193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.938729ms","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-01-30T16:04:44.758299Z","caller":"traceutil/trace.go:171","msg":"trace[1492185354] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:995; }","duration":"314.103123ms","start":"2026-01-30T16:04:44.444178Z","end":"2026-01-30T16:04:44.758281Z","steps":["trace[1492185354] 'agreement among raft nodes before linearized reading' (duration: 313.932179ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:04:44.758453Z","caller":"traceutil/trace.go:171","msg":"trace[667395287] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"507.573622ms","start":"2026-01-30T16:04:44.250828Z","end":"2026-01-30T16:04:44.758401Z","steps":["trace[667395287] 'process raft request' (duration: 506.360424ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:04:44.75888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:44.444138Z","time spent":"314.195165ms","remote":"127.0.0.1:58666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-01-30T16:04:44.759803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:04:44.250809Z","time spent":"507.685924ms","remote":"127.0.0.1:48082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:04:47.358758Z","caller":"traceutil/trace.go:171","msg":"trace[114827446] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"234.124454ms","start":"2026-01-30T16:04:47.124614Z","end":"2026-01-30T16:04:47.358739Z","steps":["trace[114827446] 'process raft request' (duration: 233.995901ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:07:46.048492Z","caller":"traceutil/trace.go:171","msg":"trace[528830847] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"117.156986ms","start":"2026-01-30T16:07:45.931317Z","end":"2026-01-30T16:07:46.048474Z","steps":["trace[528830847] 'process raft request' (duration: 117.045914ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:07:46.244062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.989042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:07:46.244197Z","caller":"traceutil/trace.go:171","msg":"trace[73146225] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1860; }","duration":"163.224493ms","start":"2026-01-30T16:07:46.080959Z","end":"2026-01-30T16:07:46.244183Z","steps":["trace[73146225] 'read index received' (duration: 45.981394ms)","trace[73146225] 'applied index is now lower than readState.Index' (duration: 117.234828ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:07:46.244228Z","caller":"traceutil/trace.go:171","msg":"trace[362008116] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"181.272314ms","start":"2026-01-30T16:07:46.062925Z","end":"2026-01-30T16:07:46.244198Z","steps":["trace[362008116] 'process raft request' (duration: 64.045686ms)","trace[362008116] 'compare' (duration: 116.796468ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:07:46.244305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.342065ms","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-01-30T16:07:46.244334Z","caller":"traceutil/trace.go:171","msg":"trace[120550871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1782; }","duration":"163.397876ms","start":"2026-01-30T16:07:46.080926Z","end":"2026-01-30T16:07:46.244323Z","steps":["trace[120550871] 'agreement among raft nodes before linearized reading' (duration: 163.294444ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:07:47.324867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.232055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:07:47.324917Z","caller":"traceutil/trace.go:171","msg":"trace[174409729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1786; }","duration":"322.329448ms","start":"2026-01-30T16:07:47.002576Z","end":"2026-01-30T16:07:47.324905Z","steps":["trace[174409729] 'range keys from in-memory index tree' (duration: 322.139443ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:07:47.32494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:07:47.00256Z","time spent":"322.375458ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:07:47.325166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.188787ms","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-01-30T16:07:47.325227Z","caller":"traceutil/trace.go:171","msg":"trace[1750782918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1786; }","duration":"186.29049ms","start":"2026-01-30T16:07:47.138921Z","end":"2026-01-30T16:07:47.325211Z","steps":["trace[1750782918] 'range keys from in-memory index tree' (duration: 186.049234ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:07:52.832914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.558896ms","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-01-30T16:07:52.834279Z","caller":"traceutil/trace.go:171","msg":"trace[133985385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1812; }","duration":"437.959689ms","start":"2026-01-30T16:07:52.396307Z","end":"2026-01-30T16:07:52.834267Z","steps":["trace[133985385] 'range keys from in-memory index tree' (duration: 436.424464ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:07:52.834578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:07:52.39629Z","time spent":"438.275736ms","remote":"127.0.0.1:48082","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\" "}