{"level":"warn","ts":"2026-03-01T13:18:41.268811Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T13:18:41.268936Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-03-01T13:18:41.26901Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T13:18:41.269017Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-03-01T13:18:41.269039Z","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-03-01T13:18:41.27005Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-03-01T13:18:41.270238Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-03-01T13:18:41.277787Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.29705ms"} {"level":"info","ts":"2026-03-01T13:18:41.293701Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-03-01T13:18:41.293915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-03-01T13:18:41.293955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-03-01T13:18:41.293979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-01T13:18:41.293994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-03-01T13:18:41.294087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-03-01T13:18:41.307681Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-01T13:18:41.311693Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-01T13:18:41.313647Z","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-03-01T13:18:41.315796Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-01T13:18:41.315991Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-01T13:18:41.31613Z","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-03-01T13:18:41.316182Z","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-03-01T13:18:41.316191Z","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-03-01T13:18:41.318628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-03-01T13:18:41.318794Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-03-01T13:18:41.323359Z","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-03-01T13:18:41.323537Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-03-01T13:18:41.32363Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-01T13:18:41.323684Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-01T13:18:41.323569Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-03-01T13:18:42.194587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-03-01T13:18:42.194664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-01T13:18:42.194701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-03-01T13:18:42.194722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-03-01T13:18:42.194734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-03-01T13:18:42.194751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-03-01T13:18:42.194773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-03-01T13:18:42.195742Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T13:18:42.196802Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-03-01T13:18:42.196812Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T13:18:42.196855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T13:18:42.197143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-01T13:18:42.197218Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-01T13:18:42.197212Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T13:18:42.197326Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T13:18:42.197361Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T13:18:42.19927Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-03-01T13:18:42.201619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-01T13:18:59.677345Z","caller":"traceutil/trace.go:171","msg":"trace[396567409] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"303.279009ms","start":"2026-03-01T13:18:59.37404Z","end":"2026-03-01T13:18:59.677319Z","steps":["trace[396567409] 'process raft request' (duration: 303.123576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:18:59.678038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:18:59.374016Z","time spent":"303.417873ms","remote":"127.0.0.1:55660","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-03-01T13:19:00.545762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.284274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-01T13:19:00.545806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.164544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T13:19:00.545891Z","caller":"traceutil/trace.go:171","msg":"trace[1464646537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:447; }","duration":"700.314578ms","start":"2026-03-01T13:18:59.845555Z","end":"2026-03-01T13:19:00.54587Z","steps":["trace[1464646537] 'range keys from in-memory index tree' (duration: 699.99098ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:19:00.545868Z","caller":"traceutil/trace.go:171","msg":"trace[695383886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:447; }","duration":"626.452348ms","start":"2026-03-01T13:18:59.919393Z","end":"2026-03-01T13:19:00.545846Z","steps":["trace[695383886] 'range keys from in-memory index tree' (duration: 626.199082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:00.545939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:18:59.845536Z","time spent":"700.394549ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T13:19:00.545956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:18:59.919382Z","time spent":"626.56105ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T13:19:00.546107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.854948ms","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-03-01T13:19:00.546189Z","caller":"traceutil/trace.go:171","msg":"trace[555718521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:447; }","duration":"652.002211ms","start":"2026-03-01T13:18:59.894167Z","end":"2026-03-01T13:19:00.546169Z","steps":["trace[555718521] 'range keys from in-memory index tree' (duration: 651.757206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:00.546232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:18:59.894144Z","time spent":"652.079403ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-01T13:19:10.040442Z","caller":"traceutil/trace.go:171","msg":"trace[1143370204] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"148.697951ms","start":"2026-03-01T13:19:09.8917Z","end":"2026-03-01T13:19:10.040398Z","steps":["trace[1143370204] 'process raft request' (duration: 148.425484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:17.96073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.84857ms","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-03-01T13:19:17.960795Z","caller":"traceutil/trace.go:171","msg":"trace[1218532904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"199.964162ms","start":"2026-03-01T13:19:17.760818Z","end":"2026-03-01T13:19:17.960782Z","steps":["trace[1218532904] 'range keys from in-memory index tree' (duration: 199.686845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:18.491056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349870843040392,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T13:19:18.49218Z","caller":"traceutil/trace.go:171","msg":"trace[1241022025] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"534.090872ms","start":"2026-03-01T13:19:17.958062Z","end":"2026-03-01T13:19:18.492153Z","steps":["trace[1241022025] 'process raft request' (duration: 533.863526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:18.492436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:19:17.958044Z","time spent":"534.198413ms","remote":"127.0.0.1:55554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:19:18.497253Z","caller":"traceutil/trace.go:171","msg":"trace[708944127] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:549; }","duration":"506.249447ms","start":"2026-03-01T13:19:17.99098Z","end":"2026-03-01T13:19:18.49723Z","steps":["trace[708944127] 'read index received' (duration: 500.816287ms)","trace[708944127] 'applied index is now lower than readState.Index' (duration: 5.43232ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:19:18.497321Z","caller":"traceutil/trace.go:171","msg":"trace[2087335366] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"533.577899ms","start":"2026-03-01T13:19:17.963707Z","end":"2026-03-01T13:19:18.497285Z","steps":["trace[2087335366] 'process raft request' (duration: 533.345754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:18.497403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.39642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:19:18.497443Z","caller":"traceutil/trace.go:171","msg":"trace[15089741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"506.484701ms","start":"2026-03-01T13:19:17.990949Z","end":"2026-03-01T13:19:18.497434Z","steps":["trace[15089741] 'agreement among raft nodes before linearized reading' (duration: 506.375769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:18.497483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:19:17.990938Z","time spent":"506.536863ms","remote":"127.0.0.1:55374","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-03-01T13:19:18.49748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:19:17.963693Z","time spent":"533.707972ms","remote":"127.0.0.1:55660","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-03-01T13:19:18.497764Z","caller":"traceutil/trace.go:171","msg":"trace[60458842] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"517.973516ms","start":"2026-03-01T13:19:17.979755Z","end":"2026-03-01T13:19:18.497728Z","steps":["trace[60458842] 'process raft request' (duration: 517.436183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:18.497929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:19:17.979741Z","time spent":"518.102288ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:19:19.333723Z","caller":"traceutil/trace.go:171","msg":"trace[2030220987] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"622.00653ms","start":"2026-03-01T13:19:18.711694Z","end":"2026-03-01T13:19:19.333701Z","steps":["trace[2030220987] 'process raft request' (duration: 621.870927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:19.333875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:19:18.711671Z","time spent":"622.135843ms","remote":"127.0.0.1:55660","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-03-01T13:19:19.385439Z","caller":"traceutil/trace.go:171","msg":"trace[2048563429] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"673.359603ms","start":"2026-03-01T13:19:18.712062Z","end":"2026-03-01T13:19:19.385422Z","steps":["trace[2048563429] 'process raft request' (duration: 673.21326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:19.385584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:19:18.712049Z","time spent":"673.467835ms","remote":"127.0.0.1:55660","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-03-01T13:19:19.385715Z","caller":"traceutil/trace.go:171","msg":"trace[1787851896] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"395.496724ms","start":"2026-03-01T13:19:18.98988Z","end":"2026-03-01T13:19:19.385376Z","steps":["trace[1787851896] 'read index received' (duration: 344.049289ms)","trace[1787851896] 'applied index is now lower than readState.Index' (duration: 51.446725ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:19:19.385621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.717669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:19:19.385766Z","caller":"traceutil/trace.go:171","msg":"trace[397166960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"395.908594ms","start":"2026-03-01T13:19:18.989838Z","end":"2026-03-01T13:19:19.385747Z","steps":["trace[397166960] 'agreement among raft nodes before linearized reading' (duration: 395.711629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:19.385816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:19:18.989821Z","time spent":"395.983615ms","remote":"127.0.0.1:55390","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-03-01T13:19:21.020071Z","caller":"traceutil/trace.go:171","msg":"trace[1958674362] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"300.355475ms","start":"2026-03-01T13:19:20.719673Z","end":"2026-03-01T13:19:21.020029Z","steps":["trace[1958674362] 'process raft request' (duration: 297.990673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:21.020443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:19:20.719658Z","time spent":"300.501448ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:19:24.867651Z","caller":"traceutil/trace.go:171","msg":"trace[1437889173] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"102.189273ms","start":"2026-03-01T13:19:24.765435Z","end":"2026-03-01T13:19:24.867624Z","steps":["trace[1437889173] 'process raft request' (duration: 101.775724ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:19:39.054135Z","caller":"traceutil/trace.go:171","msg":"trace[108510699] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"244.503363ms","start":"2026-03-01T13:19:38.809605Z","end":"2026-03-01T13:19:39.054109Z","steps":["trace[108510699] 'process raft request' (duration: 244.010292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:19:39.480029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.427131ms","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-03-01T13:19:39.480083Z","caller":"traceutil/trace.go:171","msg":"trace[246693730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"176.527754ms","start":"2026-03-01T13:19:39.303543Z","end":"2026-03-01T13:19:39.480071Z","steps":["trace[246693730] 'range keys from in-memory index tree' (duration: 176.278468ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:19:40.818304Z","caller":"traceutil/trace.go:171","msg":"trace[1276031408] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"150.867508ms","start":"2026-03-01T13:19:40.667417Z","end":"2026-03-01T13:19:40.818285Z","steps":["trace[1276031408] 'process raft request' (duration: 150.755555ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:19:49.912184Z","caller":"traceutil/trace.go:171","msg":"trace[2113615406] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"138.119257ms","start":"2026-03-01T13:19:49.774043Z","end":"2026-03-01T13:19:49.912162Z","steps":["trace[2113615406] 'process raft request' (duration: 137.980854ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:06.045397Z","caller":"traceutil/trace.go:171","msg":"trace[907348065] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"152.245511ms","start":"2026-03-01T13:20:05.893123Z","end":"2026-03-01T13:20:06.045368Z","steps":["trace[907348065] 'read index received' (duration: 152.048306ms)","trace[907348065] 'applied index is now lower than readState.Index' (duration: 195.785µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:06.045446Z","caller":"traceutil/trace.go:171","msg":"trace[215152217] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"189.91565ms","start":"2026-03-01T13:20:05.8555Z","end":"2026-03-01T13:20:06.045415Z","steps":["trace[215152217] 'process raft request' (duration: 189.680064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:06.045704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.528818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T13:20:06.04581Z","caller":"traceutil/trace.go:171","msg":"trace[1694749525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"152.685411ms","start":"2026-03-01T13:20:05.89309Z","end":"2026-03-01T13:20:06.045775Z","steps":["trace[1694749525] 'agreement among raft nodes before linearized reading' (duration: 152.411755ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:10.207856Z","caller":"traceutil/trace.go:171","msg":"trace[1082225429] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"145.956702ms","start":"2026-03-01T13:20:10.061877Z","end":"2026-03-01T13:20:10.207834Z","steps":["trace[1082225429] 'process raft request' (duration: 145.795089ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:10.208216Z","caller":"traceutil/trace.go:171","msg":"trace[1390398200] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:844; }","duration":"145.684687ms","start":"2026-03-01T13:20:10.062515Z","end":"2026-03-01T13:20:10.208199Z","steps":["trace[1390398200] 'read index received' (duration: 145.679677ms)","trace[1390398200] 'applied index is now lower than readState.Index' (duration: 3.8µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:10.208466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.919612ms","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-03-01T13:20:10.208507Z","caller":"traceutil/trace.go:171","msg":"trace[832921890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"145.996223ms","start":"2026-03-01T13:20:10.062501Z","end":"2026-03-01T13:20:10.208497Z","steps":["trace[832921890] 'agreement among raft nodes before linearized reading' (duration: 145.867101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:10.208692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.147826ms","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-03-01T13:20:10.208758Z","caller":"traceutil/trace.go:171","msg":"trace[732111801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"146.207378ms","start":"2026-03-01T13:20:10.06253Z","end":"2026-03-01T13:20:10.208737Z","steps":["trace[732111801] 'agreement among raft nodes before linearized reading' (duration: 145.753118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:10.527541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.015094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:10.528134Z","caller":"traceutil/trace.go:171","msg":"trace[1462132675] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:844; }","duration":"259.529592ms","start":"2026-03-01T13:20:10.268581Z","end":"2026-03-01T13:20:10.528111Z","steps":["trace[1462132675] 'read index received' (duration: 22.647948ms)","trace[1462132675] 'applied index is now lower than readState.Index' (duration: 236.879384ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:10.528111Z","caller":"traceutil/trace.go:171","msg":"trace[2091362170] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"314.662726ms","start":"2026-03-01T13:20:10.213404Z","end":"2026-03-01T13:20:10.528066Z","steps":["trace[2091362170] 'process raft request' (duration: 314.235927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:10.5283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.732186ms","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-03-01T13:20:10.528345Z","caller":"traceutil/trace.go:171","msg":"trace[435064023] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:811; }","duration":"204.784427ms","start":"2026-03-01T13:20:10.323548Z","end":"2026-03-01T13:20:10.528333Z","steps":["trace[435064023] 'agreement among raft nodes before linearized reading' (duration: 204.700375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:10.528349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:10.21339Z","time spent":"314.8357ms","remote":"127.0.0.1:55660","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-03-01T13:20:10.528121Z","caller":"traceutil/trace.go:171","msg":"trace[1750207847] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"315.520005ms","start":"2026-03-01T13:20:10.21256Z","end":"2026-03-01T13:20:10.52808Z","steps":["trace[1750207847] 'process raft request' (duration: 78.670831ms)","trace[1750207847] 'compare' (duration: 235.690277ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:10.528395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.757907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T13:20:10.528576Z","caller":"traceutil/trace.go:171","msg":"trace[480062742] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:811; }","duration":"259.93834ms","start":"2026-03-01T13:20:10.268556Z","end":"2026-03-01T13:20:10.528495Z","steps":["trace[480062742] 'agreement among raft nodes before linearized reading' (duration: 259.633624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:10.528661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:10.212525Z","time spent":"315.956394ms","remote":"127.0.0.1:55660","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-03-01T13:20:11.574635Z","caller":"traceutil/trace.go:171","msg":"trace[1639173826] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"159.850228ms","start":"2026-03-01T13:20:11.414758Z","end":"2026-03-01T13:20:11.574608Z","steps":["trace[1639173826] 'process raft request' (duration: 159.641394ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:12.424642Z","caller":"traceutil/trace.go:171","msg":"trace[930329502] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"207.418465ms","start":"2026-03-01T13:20:12.217197Z","end":"2026-03-01T13:20:12.424616Z","steps":["trace[930329502] 'process raft request' (duration: 207.245192ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:12.843377Z","caller":"traceutil/trace.go:171","msg":"trace[921648147] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"305.875381ms","start":"2026-03-01T13:20:12.537469Z","end":"2026-03-01T13:20:12.843344Z","steps":["trace[921648147] 'process raft request' (duration: 216.462094ms)","trace[921648147] 'compare' (duration: 88.981358ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:12.843545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:12.537441Z","time spent":"306.022665ms","remote":"127.0.0.1:55660","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-03-01T13:20:13.364617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.465649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T13:20:13.364665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.642747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T13:20:13.364711Z","caller":"traceutil/trace.go:171","msg":"trace[979528172] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:816; }","duration":"104.588231ms","start":"2026-03-01T13:20:13.260104Z","end":"2026-03-01T13:20:13.364692Z","steps":["trace[979528172] 'count revisions from in-memory index tree' (duration: 104.377437ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:13.36475Z","caller":"traceutil/trace.go:171","msg":"trace[523823599] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:816; }","duration":"191.799371ms","start":"2026-03-01T13:20:13.172933Z","end":"2026-03-01T13:20:13.364733Z","steps":["trace[523823599] 'count revisions from in-memory index tree' (duration: 191.557485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:13.365027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.354759ms","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-03-01T13:20:13.365151Z","caller":"traceutil/trace.go:171","msg":"trace[395412435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:816; }","duration":"409.574444ms","start":"2026-03-01T13:20:12.955564Z","end":"2026-03-01T13:20:13.365138Z","steps":["trace[395412435] 'range keys from in-memory index tree' (duration: 409.092183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:13.365306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:12.955545Z","time spent":"409.707176ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-01T13:20:13.682426Z","caller":"traceutil/trace.go:171","msg":"trace[917967949] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"103.847026ms","start":"2026-03-01T13:20:13.578543Z","end":"2026-03-01T13:20:13.68239Z","steps":["trace[917967949] 'read index received' (duration: 103.717973ms)","trace[917967949] 'applied index is now lower than readState.Index' (duration: 127.823µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:13.682498Z","caller":"traceutil/trace.go:171","msg":"trace[1087472615] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"302.271122ms","start":"2026-03-01T13:20:13.380179Z","end":"2026-03-01T13:20:13.68245Z","steps":["trace[1087472615] 'process raft request' (duration: 302.049517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:13.682887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.325106ms","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-03-01T13:20:13.682944Z","caller":"traceutil/trace.go:171","msg":"trace[1032825377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:817; }","duration":"104.427328ms","start":"2026-03-01T13:20:13.578505Z","end":"2026-03-01T13:20:13.682932Z","steps":["trace[1032825377] 'agreement among raft nodes before linearized reading' (duration: 104.06452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:13.683448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:13.38016Z","time spent":"302.709312ms","remote":"127.0.0.1:55660","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-03-01T13:20:14.921644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.288802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:14.921746Z","caller":"traceutil/trace.go:171","msg":"trace[2096040231] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"930.296594ms","start":"2026-03-01T13:20:13.991433Z","end":"2026-03-01T13:20:14.92173Z","steps":["trace[2096040231] 'read index received' (duration: 34.931µs)","trace[2096040231] 'applied index is now lower than readState.Index' (duration: 930.260753ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:14.92194Z","caller":"traceutil/trace.go:171","msg":"trace[2033972445] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"1.234166832s","start":"2026-03-01T13:20:13.687761Z","end":"2026-03-01T13:20:14.921928Z","steps":["trace[2033972445] 'process raft request' (duration: 294.509872ms)","trace[2033972445] 'compare' (duration: 939.19134ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:14.922007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:13.687735Z","time spent":"1.234232353s","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:20:14.922235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.807205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:20:14.92227Z","caller":"traceutil/trace.go:171","msg":"trace[567976775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"930.869897ms","start":"2026-03-01T13:20:13.991388Z","end":"2026-03-01T13:20:14.922258Z","steps":["trace[567976775] 'agreement among raft nodes before linearized reading' (duration: 930.815056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:14.922297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:13.991372Z","time spent":"930.919728ms","remote":"127.0.0.1:55390","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-03-01T13:20:14.922422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.212805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-01T13:20:14.922498Z","caller":"traceutil/trace.go:171","msg":"trace[2054947813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"493.335778ms","start":"2026-03-01T13:20:14.429143Z","end":"2026-03-01T13:20:14.922479Z","steps":["trace[2054947813] 'agreement among raft nodes before linearized reading' (duration: 493.131483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:14.922534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.136979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-01T13:20:14.922543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:14.429101Z","time spent":"493.43354ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-01T13:20:14.922576Z","caller":"traceutil/trace.go:171","msg":"trace[1816338008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"302.218071ms","start":"2026-03-01T13:20:14.620349Z","end":"2026-03-01T13:20:14.922567Z","steps":["trace[1816338008] 'agreement among raft nodes before linearized reading' (duration: 302.091488ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:14.922604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:14.620327Z","time spent":"302.270942ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T13:20:15.493643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349870843041057,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T13:20:15.522044Z","caller":"traceutil/trace.go:171","msg":"trace[395856522] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"594.873871ms","start":"2026-03-01T13:20:14.927142Z","end":"2026-03-01T13:20:15.522016Z","steps":["trace[395856522] 'process raft request' (duration: 594.81428ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:15.522307Z","caller":"traceutil/trace.go:171","msg":"trace[405285926] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"595.145467ms","start":"2026-03-01T13:20:14.927082Z","end":"2026-03-01T13:20:15.522227Z","steps":["trace[405285926] 'process raft request' (duration: 594.680407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:15.522469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:14.927116Z","time spent":"595.015375ms","remote":"127.0.0.1:55660","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-03-01T13:20:15.522745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:14.927063Z","time spent":"595.531697ms","remote":"127.0.0.1:55660","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-03-01T13:20:15.534159Z","caller":"traceutil/trace.go:171","msg":"trace[2143290448] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:855; }","duration":"541.511898ms","start":"2026-03-01T13:20:14.992623Z","end":"2026-03-01T13:20:15.534135Z","steps":["trace[2143290448] 'read index received' (duration: 529.081774ms)","trace[2143290448] 'applied index is now lower than readState.Index' (duration: 12.428844ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:15.534318Z","caller":"traceutil/trace.go:171","msg":"trace[1237861093] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"410.614247ms","start":"2026-03-01T13:20:15.123689Z","end":"2026-03-01T13:20:15.534304Z","steps":["trace[1237861093] 'process raft request' (duration: 410.315811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:15.534459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:15.123667Z","time spent":"410.698169ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:20:15.5346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.978558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:20:15.534647Z","caller":"traceutil/trace.go:171","msg":"trace[2024343641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"542.052679ms","start":"2026-03-01T13:20:14.992583Z","end":"2026-03-01T13:20:15.534636Z","steps":["trace[2024343641] 'agreement among raft nodes before linearized reading' (duration: 541.985698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:15.534674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:14.992567Z","time spent":"542.099741ms","remote":"127.0.0.1:55390","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-03-01T13:20:26.009063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.054366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:26.009333Z","caller":"traceutil/trace.go:171","msg":"trace[1657617192] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"341.121129ms","start":"2026-03-01T13:20:25.668163Z","end":"2026-03-01T13:20:26.009284Z","steps":["trace[1657617192] 'process raft request' (duration: 341.014526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:26.009403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:25.668145Z","time spent":"341.22529ms","remote":"127.0.0.1:55660","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-03-01T13:20:26.009588Z","caller":"traceutil/trace.go:171","msg":"trace[1644485290] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"434.042563ms","start":"2026-03-01T13:20:25.575521Z","end":"2026-03-01T13:20:26.009564Z","steps":["trace[1644485290] 'read index received' (duration: 72.309851ms)","trace[1644485290] 'applied index is now lower than readState.Index' (duration: 361.730962ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:26.009614Z","caller":"traceutil/trace.go:171","msg":"trace[1667353080] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"439.741958ms","start":"2026-03-01T13:20:25.569843Z","end":"2026-03-01T13:20:26.009585Z","steps":["trace[1667353080] 'process raft request' (duration: 78.064708ms)","trace[1667353080] 'compare' (duration: 360.866692ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:26.009784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:25.569825Z","time spent":"439.871391ms","remote":"127.0.0.1:55422","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-03-01T13:20:26.009801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.270178ms","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-03-01T13:20:26.009835Z","caller":"traceutil/trace.go:171","msg":"trace[2044763607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"434.360089ms","start":"2026-03-01T13:20:25.575464Z","end":"2026-03-01T13:20:26.009824Z","steps":["trace[2044763607] 'agreement among raft nodes before linearized reading' (duration: 434.222366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:26.009867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:25.575443Z","time spent":"434.415731ms","remote":"127.0.0.1:55660","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-03-01T13:20:26.010045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.677082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-01T13:20:26.010094Z","caller":"traceutil/trace.go:171","msg":"trace[413591616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"427.758754ms","start":"2026-03-01T13:20:25.58232Z","end":"2026-03-01T13:20:26.010079Z","steps":["trace[413591616] 'agreement among raft nodes before linearized reading' (duration: 427.60613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:26.010125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:25.582267Z","time spent":"427.850766ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T13:20:26.010218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.758927ms","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-03-01T13:20:26.010249Z","caller":"traceutil/trace.go:171","msg":"trace[1664709618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"244.819948ms","start":"2026-03-01T13:20:25.765421Z","end":"2026-03-01T13:20:26.010241Z","steps":["trace[1664709618] 'agreement among raft nodes before linearized reading' (duration: 244.734196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:26.515324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349870843041243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T13:20:26.821018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.582335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:26.821291Z","caller":"traceutil/trace.go:171","msg":"trace[1195405856] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"807.034351ms","start":"2026-03-01T13:20:26.014231Z","end":"2026-03-01T13:20:26.821266Z","steps":["trace[1195405856] 'process raft request' (duration: 656.102759ms)","trace[1195405856] 'compare' (duration: 150.244187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:26.821404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:26.014215Z","time spent":"807.150314ms","remote":"127.0.0.1:55660","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-03-01T13:20:26.821597Z","caller":"traceutil/trace.go:171","msg":"trace[1674712770] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"806.728955ms","start":"2026-03-01T13:20:26.014849Z","end":"2026-03-01T13:20:26.821578Z","steps":["trace[1674712770] 'process raft request' (duration: 806.309176ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:26.821655Z","caller":"traceutil/trace.go:171","msg":"trace[892439401] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"805.403326ms","start":"2026-03-01T13:20:26.016235Z","end":"2026-03-01T13:20:26.821638Z","steps":["trace[892439401] 'process raft request' (duration: 805.282634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:26.821675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.232926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-03-01T13:20:26.821703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:26.014839Z","time spent":"806.811747ms","remote":"127.0.0.1:55660","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-03-01T13:20:26.82171Z","caller":"traceutil/trace.go:171","msg":"trace[48409271] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:903; }","duration":"807.290208ms","start":"2026-03-01T13:20:26.014412Z","end":"2026-03-01T13:20:26.821702Z","steps":["trace[48409271] 'agreement among raft nodes before linearized reading' (duration: 807.222386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:26.821734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:26.014406Z","time spent":"807.323428ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-03-01T13:20:26.821742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:26.016228Z","time spent":"805.473558ms","remote":"127.0.0.1:55660","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-03-01T13:20:26.821602Z","caller":"traceutil/trace.go:171","msg":"trace[1329196578] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:941; }","duration":"807.154034ms","start":"2026-03-01T13:20:26.014437Z","end":"2026-03-01T13:20:26.821591Z","steps":["trace[1329196578] 'read index received' (duration: 655.920536ms)","trace[1329196578] 'applied index is now lower than readState.Index' (duration: 151.232618ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:28.968652Z","caller":"traceutil/trace.go:171","msg":"trace[1584601374] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"139.509779ms","start":"2026-03-01T13:20:28.829106Z","end":"2026-03-01T13:20:28.968616Z","steps":["trace[1584601374] 'process raft request' (duration: 139.385276ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:28.972135Z","caller":"traceutil/trace.go:171","msg":"trace[1625411884] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:946; }","duration":"142.628629ms","start":"2026-03-01T13:20:28.82949Z","end":"2026-03-01T13:20:28.972119Z","steps":["trace[1625411884] 'read index received' (duration: 139.014279ms)","trace[1625411884] 'applied index is now lower than readState.Index' (duration: 3.61382ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:28.972288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.788022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-01T13:20:28.972253Z","caller":"traceutil/trace.go:171","msg":"trace[1720410178] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"142.800463ms","start":"2026-03-01T13:20:28.829425Z","end":"2026-03-01T13:20:28.972225Z","steps":["trace[1720410178] 'process raft request' (duration: 142.615149ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:28.972317Z","caller":"traceutil/trace.go:171","msg":"trace[2077927267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"142.871374ms","start":"2026-03-01T13:20:28.829438Z","end":"2026-03-01T13:20:28.972309Z","steps":["trace[2077927267] 'agreement among raft nodes before linearized reading' (duration: 142.745581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:31.4721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349870843041270,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T13:20:31.973253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995349870843041270,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T13:20:32.227365Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.270575463s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-01T13:20:32.76489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.206973ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-01T13:20:32.764977Z","caller":"traceutil/trace.go:171","msg":"trace[2147043551] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"1.793077894s","start":"2026-03-01T13:20:30.971883Z","end":"2026-03-01T13:20:32.764961Z","steps":["trace[2147043551] 'read index received' (duration: 1.255618135s)","trace[2147043551] 'applied index is now lower than readState.Index' (duration: 537.458419ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:32.765189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793296679s","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-03-01T13:20:32.765216Z","caller":"traceutil/trace.go:171","msg":"trace[2142236439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:911; }","duration":"1.79336565s","start":"2026-03-01T13:20:30.971842Z","end":"2026-03-01T13:20:32.765208Z","steps":["trace[2142236439] 'agreement among raft nodes before linearized reading' (duration: 1.793214597s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:32.76525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:30.971823Z","time spent":"1.793419401s","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-01T13:20:32.765301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622908448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-01T13:20:32.765313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78838366s","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-03-01T13:20:32.765384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.95127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T13:20:32.765408Z","caller":"traceutil/trace.go:171","msg":"trace[716125624] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:911; }","duration":"956.004101ms","start":"2026-03-01T13:20:31.809399Z","end":"2026-03-01T13:20:32.765403Z","steps":["trace[716125624] 'agreement among raft nodes before linearized reading' (duration: 955.96376ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:32.76539Z","caller":"traceutil/trace.go:171","msg":"trace[1922920414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.788518713s","start":"2026-03-01T13:20:30.976853Z","end":"2026-03-01T13:20:32.765372Z","steps":["trace[1922920414] 'agreement among raft nodes before linearized reading' (duration: 1.788271678s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:32.765426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:31.809383Z","time spent":"956.037942ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-01T13:20:32.765439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:30.976831Z","time spent":"1.788598785s","remote":"127.0.0.1:55660","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-03-01T13:20:32.765429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.058879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T13:20:32.765482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775997757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-01T13:20:32.765509Z","caller":"traceutil/trace.go:171","msg":"trace[479233836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"775.17214ms","start":"2026-03-01T13:20:31.990312Z","end":"2026-03-01T13:20:32.765484Z","steps":["trace[479233836] 'agreement among raft nodes before linearized reading' (duration: 775.032137ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:32.76553Z","caller":"traceutil/trace.go:171","msg":"trace[412886755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.776090989s","start":"2026-03-01T13:20:30.989427Z","end":"2026-03-01T13:20:32.765518Z","steps":["trace[412886755] 'agreement among raft nodes before linearized reading' (duration: 1.775913805s)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:32.765348Z","caller":"traceutil/trace.go:171","msg":"trace[997628243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.62299539s","start":"2026-03-01T13:20:31.142339Z","end":"2026-03-01T13:20:32.765335Z","steps":["trace[997628243] 'agreement among raft nodes before linearized reading' (duration: 1.622821986s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:32.765502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.712605832s","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-03-01T13:20:32.765549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:31.990297Z","time spent":"775.244393ms","remote":"127.0.0.1:55390","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-03-01T13:20:32.765559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:30.989409Z","time spent":"1.77614571s","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T13:20:32.765565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:31.142322Z","time spent":"1.623234916s","remote":"127.0.0.1:55660","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-03-01T13:20:32.765593Z","caller":"traceutil/trace.go:171","msg":"trace[2032604372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"1.712742735s","start":"2026-03-01T13:20:31.052827Z","end":"2026-03-01T13:20:32.765569Z","steps":["trace[2032604372] 'agreement among raft nodes before linearized reading' (duration: 1.7124889s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:32.765648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:31.052808Z","time spent":"1.712827628s","remote":"127.0.0.1:55660","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":"info","ts":"2026-03-01T13:20:33.047271Z","caller":"traceutil/trace.go:171","msg":"trace[515544351] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"278.10147ms","start":"2026-03-01T13:20:32.769144Z","end":"2026-03-01T13:20:33.047246Z","steps":["trace[515544351] 'process raft request' (duration: 277.63682ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:33.047322Z","caller":"traceutil/trace.go:171","msg":"trace[1447375804] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"277.915106ms","start":"2026-03-01T13:20:32.769377Z","end":"2026-03-01T13:20:33.047293Z","steps":["trace[1447375804] 'process raft request' (duration: 277.661561ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:33.047456Z","caller":"traceutil/trace.go:171","msg":"trace[6258164] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"277.898326ms","start":"2026-03-01T13:20:32.76953Z","end":"2026-03-01T13:20:33.047428Z","steps":["trace[6258164] 'process raft request' (duration: 277.59891ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:33.048024Z","caller":"traceutil/trace.go:171","msg":"trace[1724302809] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"277.962849ms","start":"2026-03-01T13:20:32.770049Z","end":"2026-03-01T13:20:33.048012Z","steps":["trace[1724302809] 'process raft request' (duration: 277.12088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:50.490931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.683305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:20:50.491063Z","caller":"traceutil/trace.go:171","msg":"trace[536389856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"498.86659ms","start":"2026-03-01T13:20:49.992181Z","end":"2026-03-01T13:20:50.491047Z","steps":["trace[536389856] 'range keys from in-memory index tree' (duration: 498.586243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:50.491067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.926422ms","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-03-01T13:20:50.491176Z","caller":"traceutil/trace.go:171","msg":"trace[1265196840] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:982; }","duration":"664.095566ms","start":"2026-03-01T13:20:49.827059Z","end":"2026-03-01T13:20:50.491155Z","steps":["trace[1265196840] 'count revisions from in-memory index tree' (duration: 663.791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:50.491244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:49.827008Z","time spent":"664.213458ms","remote":"127.0.0.1:55828","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-03-01T13:20:50.491116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:49.99216Z","time spent":"498.947811ms","remote":"127.0.0.1:55390","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-03-01T13:20:50.837838Z","caller":"traceutil/trace.go:171","msg":"trace[1805131124] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"345.535085ms","start":"2026-03-01T13:20:50.492279Z","end":"2026-03-01T13:20:50.837814Z","steps":["trace[1805131124] 'read index received' (duration: 345.083295ms)","trace[1805131124] 'applied index is now lower than readState.Index' (duration: 450.74µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:50.837935Z","caller":"traceutil/trace.go:171","msg":"trace[1010490277] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"584.208818ms","start":"2026-03-01T13:20:50.253688Z","end":"2026-03-01T13:20:50.837897Z","steps":["trace[1010490277] 'process raft request' (duration: 583.616886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:50.838109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:50.253664Z","time spent":"584.350141ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:20:50.838153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.842701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:20:50.838354Z","caller":"traceutil/trace.go:171","msg":"trace[1939071862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"346.101317ms","start":"2026-03-01T13:20:50.492224Z","end":"2026-03-01T13:20:50.838326Z","steps":["trace[1939071862] 'agreement among raft nodes before linearized reading' (duration: 345.715768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:50.838458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:50.492209Z","time spent":"346.233159ms","remote":"127.0.0.1:55374","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-03-01T13:20:50.888654Z","caller":"traceutil/trace.go:171","msg":"trace[113582369] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"173.615561ms","start":"2026-03-01T13:20:50.715009Z","end":"2026-03-01T13:20:50.888624Z","steps":["trace[113582369] 'process raft request' (duration: 173.238322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:53.6711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.883624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:53.671193Z","caller":"traceutil/trace.go:171","msg":"trace[1617768742] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"367.251222ms","start":"2026-03-01T13:20:53.303926Z","end":"2026-03-01T13:20:53.671177Z","steps":["trace[1617768742] 'read index received' (duration: 254.134444ms)","trace[1617768742] 'applied index is now lower than readState.Index' (duration: 113.115648ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:53.671282Z","caller":"traceutil/trace.go:171","msg":"trace[531646484] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"379.113644ms","start":"2026-03-01T13:20:53.292158Z","end":"2026-03-01T13:20:53.671272Z","steps":["trace[531646484] 'process raft request' (duration: 265.964994ms)","trace[531646484] 'compare' (duration: 112.785732ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:53.671336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:53.292139Z","time spent":"379.168434ms","remote":"127.0.0.1:55660","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-03-01T13:20:53.671722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.930954ms","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-03-01T13:20:53.671786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.839276ms","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-03-01T13:20:53.671884Z","caller":"traceutil/trace.go:171","msg":"trace[26121412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"336.073516ms","start":"2026-03-01T13:20:53.335733Z","end":"2026-03-01T13:20:53.671807Z","steps":["trace[26121412] 'agreement among raft nodes before linearized reading' (duration: 335.669716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:53.672085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:53.335713Z","time spent":"336.326462ms","remote":"127.0.0.1:55660","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-03-01T13:20:53.671883Z","caller":"traceutil/trace.go:171","msg":"trace[1117072842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"367.995199ms","start":"2026-03-01T13:20:53.303865Z","end":"2026-03-01T13:20:53.67186Z","steps":["trace[1117072842] 'agreement among raft nodes before linearized reading' (duration: 367.730764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:53.672234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:53.303847Z","time spent":"368.370188ms","remote":"127.0.0.1:55660","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":"info","ts":"2026-03-01T13:20:54.222485Z","caller":"traceutil/trace.go:171","msg":"trace[137335361] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1044; }","duration":"230.861121ms","start":"2026-03-01T13:20:53.991595Z","end":"2026-03-01T13:20:54.222456Z","steps":["trace[137335361] 'read index received' (duration: 153.611661ms)","trace[137335361] 'applied index is now lower than readState.Index' (duration: 77.2482ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:54.222599Z","caller":"traceutil/trace.go:171","msg":"trace[2088739498] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"546.015877ms","start":"2026-03-01T13:20:53.676558Z","end":"2026-03-01T13:20:54.222573Z","steps":["trace[2088739498] 'process raft request' (duration: 468.640075ms)","trace[2088739498] 'compare' (duration: 77.063366ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:54.222673Z","caller":"traceutil/trace.go:171","msg":"trace[783056761] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"545.944356ms","start":"2026-03-01T13:20:53.676712Z","end":"2026-03-01T13:20:54.222656Z","steps":["trace[783056761] 'process raft request' (duration: 545.682201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:54.222728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:53.67653Z","time spent":"546.128919ms","remote":"127.0.0.1:55660","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-03-01T13:20:54.222782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:53.676687Z","time spent":"546.025577ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:20:54.22262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.008594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:20:54.222876Z","caller":"traceutil/trace.go:171","msg":"trace[1314911468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"231.307361ms","start":"2026-03-01T13:20:53.991556Z","end":"2026-03-01T13:20:54.222864Z","steps":["trace[1314911468] 'agreement among raft nodes before linearized reading' (duration: 231.010444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:55.555153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.99146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-01T13:20:55.55524Z","caller":"traceutil/trace.go:171","msg":"trace[1573425192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"264.117272ms","start":"2026-03-01T13:20:55.291102Z","end":"2026-03-01T13:20:55.55522Z","steps":["trace[1573425192] 'range keys from in-memory index tree' (duration: 263.843357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:55.555817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.946263ms","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-03-01T13:20:55.555906Z","caller":"traceutil/trace.go:171","msg":"trace[688896143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"515.169758ms","start":"2026-03-01T13:20:55.040715Z","end":"2026-03-01T13:20:55.555885Z","steps":["trace[688896143] 'range keys from in-memory index tree' (duration: 514.004353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:55.555986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:55.040701Z","time spent":"515.26906ms","remote":"127.0.0.1:55660","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-03-01T13:20:55.903725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.027997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:55.903875Z","caller":"traceutil/trace.go:171","msg":"trace[2083688752] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"345.255259ms","start":"2026-03-01T13:20:55.558601Z","end":"2026-03-01T13:20:55.903857Z","steps":["trace[2083688752] 'process raft request' (duration: 237.026437ms)","trace[2083688752] 'compare' (duration: 107.902205ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:55.903996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:55.55858Z","time spent":"345.32751ms","remote":"127.0.0.1:55660","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-03-01T13:20:55.904136Z","caller":"traceutil/trace.go:171","msg":"trace[617052907] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1047; }","duration":"228.899297ms","start":"2026-03-01T13:20:55.675228Z","end":"2026-03-01T13:20:55.904127Z","steps":["trace[617052907] 'read index received' (duration: 28.014207ms)","trace[617052907] 'applied index is now lower than readState.Index' (duration: 200.88406ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:55.904273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.053071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-01T13:20:55.9043Z","caller":"traceutil/trace.go:171","msg":"trace[955418609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"229.118403ms","start":"2026-03-01T13:20:55.675172Z","end":"2026-03-01T13:20:55.904291Z","steps":["trace[955418609] 'agreement among raft nodes before linearized reading' (duration: 228.99263ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:55.904303Z","caller":"traceutil/trace.go:171","msg":"trace[1106887917] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"344.965973ms","start":"2026-03-01T13:20:55.559325Z","end":"2026-03-01T13:20:55.904291Z","steps":["trace[1106887917] 'process raft request' (duration: 344.483642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:55.904346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:55.559308Z","time spent":"345.015804ms","remote":"127.0.0.1:55660","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-03-01T13:20:55.961214Z","caller":"traceutil/trace.go:171","msg":"trace[572780040] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"257.089548ms","start":"2026-03-01T13:20:55.704103Z","end":"2026-03-01T13:20:55.961193Z","steps":["trace[572780040] 'process raft request' (duration: 256.942415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:56.55381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.798564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:56.553908Z","caller":"traceutil/trace.go:171","msg":"trace[533425657] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"590.606637ms","start":"2026-03-01T13:20:55.963286Z","end":"2026-03-01T13:20:56.553893Z","steps":["trace[533425657] 'read index received' (duration: 456.486386ms)","trace[533425657] 'applied index is now lower than readState.Index' (duration: 134.119241ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:56.553983Z","caller":"traceutil/trace.go:171","msg":"trace[1812970622] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"645.812833ms","start":"2026-03-01T13:20:55.90816Z","end":"2026-03-01T13:20:56.553972Z","steps":["trace[1812970622] 'process raft request' (duration: 511.760273ms)","trace[1812970622] 'compare' (duration: 133.702222ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:56.554042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:55.90813Z","time spent":"645.884344ms","remote":"127.0.0.1:55660","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-03-01T13:20:56.554075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.224204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:20:56.554138Z","caller":"traceutil/trace.go:171","msg":"trace[1592982497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"562.312546ms","start":"2026-03-01T13:20:55.991806Z","end":"2026-03-01T13:20:56.554118Z","steps":["trace[1592982497] 'agreement among raft nodes before linearized reading' (duration: 562.214613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:56.554179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:55.991793Z","time spent":"562.376137ms","remote":"127.0.0.1:55390","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-03-01T13:20:56.554374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.227854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-03-01T13:20:56.554405Z","caller":"traceutil/trace.go:171","msg":"trace[1340549853] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1004; }","duration":"258.284745ms","start":"2026-03-01T13:20:56.29611Z","end":"2026-03-01T13:20:56.554395Z","steps":["trace[1340549853] 'agreement among raft nodes before linearized reading' (duration: 258.178322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:56.554477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.763692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-01T13:20:56.554477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.970299ms","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-03-01T13:20:56.554503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.167041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-01T13:20:56.554552Z","caller":"traceutil/trace.go:171","msg":"trace[1861498795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"326.883725ms","start":"2026-03-01T13:20:56.227648Z","end":"2026-03-01T13:20:56.554532Z","steps":["trace[1861498795] 'agreement among raft nodes before linearized reading' (duration: 326.66002ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:56.55456Z","caller":"traceutil/trace.go:171","msg":"trace[954293465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"323.090801ms","start":"2026-03-01T13:20:56.231451Z","end":"2026-03-01T13:20:56.554542Z","steps":["trace[954293465] 'agreement among raft nodes before linearized reading' (duration: 322.841556ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:56.554581Z","caller":"traceutil/trace.go:171","msg":"trace[325221193] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1004; }","duration":"591.312963ms","start":"2026-03-01T13:20:55.963247Z","end":"2026-03-01T13:20:56.55456Z","steps":["trace[325221193] 'agreement among raft nodes before linearized reading' (duration: 591.099199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:56.5546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:56.227627Z","time spent":"326.963946ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T13:20:56.5546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:56.231249Z","time spent":"323.342577ms","remote":"127.0.0.1:55660","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-03-01T13:20:56.55463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:55.963233Z","time spent":"591.387416ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-03-01T13:20:56.8115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.459863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:56.81181Z","caller":"traceutil/trace.go:171","msg":"trace[1071888365] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"253.534809ms","start":"2026-03-01T13:20:56.55824Z","end":"2026-03-01T13:20:56.811775Z","steps":["trace[1071888365] 'process raft request' (duration: 111.710579ms)","trace[1071888365] 'compare' (duration: 141.3332ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:56.812167Z","caller":"traceutil/trace.go:171","msg":"trace[1415665703] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"253.530081ms","start":"2026-03-01T13:20:56.55862Z","end":"2026-03-01T13:20:56.81215Z","steps":["trace[1415665703] 'process raft request' (duration: 252.995048ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:56.812627Z","caller":"traceutil/trace.go:171","msg":"trace[2079624907] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1051; }","duration":"253.902698ms","start":"2026-03-01T13:20:56.558692Z","end":"2026-03-01T13:20:56.812595Z","steps":["trace[2079624907] 'read index received' (duration: 111.268679ms)","trace[2079624907] 'applied index is now lower than readState.Index' (duration: 142.632739ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:56.812736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.032221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-01T13:20:56.812788Z","caller":"traceutil/trace.go:171","msg":"trace[1784239472] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1007; }","duration":"254.111353ms","start":"2026-03-01T13:20:56.558661Z","end":"2026-03-01T13:20:56.812773Z","steps":["trace[1784239472] 'agreement among raft nodes before linearized reading' (duration: 254.019451ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:56.813039Z","caller":"traceutil/trace.go:171","msg":"trace[1138337379] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"253.260724ms","start":"2026-03-01T13:20:56.559762Z","end":"2026-03-01T13:20:56.813022Z","steps":["trace[1138337379] 'process raft request' (duration: 252.318723ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:57.843469Z","caller":"traceutil/trace.go:171","msg":"trace[754358085] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"815.237032ms","start":"2026-03-01T13:20:57.028209Z","end":"2026-03-01T13:20:57.843446Z","steps":["trace[754358085] 'process raft request' (duration: 815.082088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:57.843611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:57.028182Z","time spent":"815.359475ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:20:58.779398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.283609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-01T13:20:58.779465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.81175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:20:58.779498Z","caller":"traceutil/trace.go:171","msg":"trace[374728160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"220.432752ms","start":"2026-03-01T13:20:58.559042Z","end":"2026-03-01T13:20:58.779475Z","steps":["trace[374728160] 'range keys from in-memory index tree' (duration: 220.016313ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:58.779521Z","caller":"traceutil/trace.go:171","msg":"trace[1151009121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"788.899223ms","start":"2026-03-01T13:20:57.990605Z","end":"2026-03-01T13:20:58.779505Z","steps":["trace[1151009121] 'range keys from in-memory index tree' (duration: 788.736289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:58.779554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:57.990588Z","time spent":"788.958455ms","remote":"127.0.0.1:55390","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-03-01T13:20:58.77979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.325838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-01T13:20:58.77985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.94992ms","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-03-01T13:20:58.779881Z","caller":"traceutil/trace.go:171","msg":"trace[1245201086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1008; }","duration":"872.439411ms","start":"2026-03-01T13:20:57.9074Z","end":"2026-03-01T13:20:58.77984Z","steps":["trace[1245201086] 'range keys from in-memory index tree' (duration: 872.130213ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:58.779904Z","caller":"traceutil/trace.go:171","msg":"trace[1842886890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"872.015912ms","start":"2026-03-01T13:20:57.907866Z","end":"2026-03-01T13:20:58.779882Z","steps":["trace[1842886890] 'range keys from in-memory index tree' (duration: 871.759585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:58.779972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:57.907854Z","time spent":"872.110324ms","remote":"127.0.0.1:55660","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-03-01T13:20:58.780025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:57.907384Z","time spent":"872.628045ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-01T13:20:58.919219Z","caller":"traceutil/trace.go:171","msg":"trace[477198846] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"135.263368ms","start":"2026-03-01T13:20:58.783812Z","end":"2026-03-01T13:20:58.919075Z","steps":["trace[477198846] 'process raft request' (duration: 135.044853ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:58.921598Z","caller":"traceutil/trace.go:171","msg":"trace[1908018506] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"137.677081ms","start":"2026-03-01T13:20:58.783897Z","end":"2026-03-01T13:20:58.921574Z","steps":["trace[1908018506] 'process raft request' (duration: 137.442846ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:58.921629Z","caller":"traceutil/trace.go:171","msg":"trace[1183955527] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1055; }","duration":"105.666166ms","start":"2026-03-01T13:20:58.815858Z","end":"2026-03-01T13:20:58.921524Z","steps":["trace[1183955527] 'read index received' (duration: 103.095339ms)","trace[1183955527] 'applied index is now lower than readState.Index' (duration: 2.569937ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:20:58.921763Z","caller":"traceutil/trace.go:171","msg":"trace[1796048189] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"137.680279ms","start":"2026-03-01T13:20:58.784069Z","end":"2026-03-01T13:20:58.92175Z","steps":["trace[1796048189] 'process raft request' (duration: 137.416084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:58.922024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.150106ms","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-03-01T13:20:58.922056Z","caller":"traceutil/trace.go:171","msg":"trace[504993020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"106.210808ms","start":"2026-03-01T13:20:58.815835Z","end":"2026-03-01T13:20:58.922045Z","steps":["trace[504993020] 'agreement among raft nodes before linearized reading' (duration: 106.094065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:58.922235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.150816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-01T13:20:58.922269Z","caller":"traceutil/trace.go:171","msg":"trace[159905522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"106.198178ms","start":"2026-03-01T13:20:58.816063Z","end":"2026-03-01T13:20:58.922261Z","steps":["trace[159905522] 'agreement among raft nodes before linearized reading' (duration: 106.086735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:59.364913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.280144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:20:59.365122Z","caller":"traceutil/trace.go:171","msg":"trace[1326657335] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1058; }","duration":"373.818147ms","start":"2026-03-01T13:20:58.991282Z","end":"2026-03-01T13:20:59.3651Z","steps":["trace[1326657335] 'read index received' (duration: 215.368489ms)","trace[1326657335] 'applied index is now lower than readState.Index' (duration: 158.445737ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:59.365208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.937499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:20:59.365258Z","caller":"traceutil/trace.go:171","msg":"trace[1802937531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"374.011732ms","start":"2026-03-01T13:20:58.991233Z","end":"2026-03-01T13:20:59.365245Z","steps":["trace[1802937531] 'agreement among raft nodes before linearized reading' (duration: 373.93514ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:20:59.365258Z","caller":"traceutil/trace.go:171","msg":"trace[1884533795] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"438.777657ms","start":"2026-03-01T13:20:58.926447Z","end":"2026-03-01T13:20:59.365224Z","steps":["trace[1884533795] 'process raft request' (duration: 280.091995ms)","trace[1884533795] 'compare' (duration: 157.840034ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:20:59.365311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:58.991196Z","time spent":"374.103294ms","remote":"127.0.0.1:55390","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-03-01T13:20:59.365373Z","caller":"traceutil/trace.go:171","msg":"trace[1105654951] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"438.318596ms","start":"2026-03-01T13:20:58.927036Z","end":"2026-03-01T13:20:59.365354Z","steps":["trace[1105654951] 'process raft request' (duration: 437.988589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:20:59.36543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:58.92643Z","time spent":"438.91829ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:20:59.365483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:20:58.92702Z","time spent":"438.410548ms","remote":"127.0.0.1:55660","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-03-01T13:21:02.416577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.423632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:21:02.418348Z","caller":"traceutil/trace.go:171","msg":"trace[1055706575] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1029; }","duration":"188.235642ms","start":"2026-03-01T13:21:02.230085Z","end":"2026-03-01T13:21:02.41832Z","steps":["trace[1055706575] 'range keys from in-memory index tree' (duration: 186.358661ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:21:04.640993Z","caller":"traceutil/trace.go:171","msg":"trace[565877655] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"302.448773ms","start":"2026-03-01T13:21:04.338521Z","end":"2026-03-01T13:21:04.64097Z","steps":["trace[565877655] 'process raft request' (duration: 302.27656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:21:04.641118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:21:04.33849Z","time spent":"302.569016ms","remote":"127.0.0.1:55460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:21:39.93377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.631318ms","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-03-01T13:21:39.933881Z","caller":"traceutil/trace.go:171","msg":"trace[1074906198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"105.801752ms","start":"2026-03-01T13:21:39.828055Z","end":"2026-03-01T13:21:39.933857Z","steps":["trace[1074906198] 'range keys from in-memory index tree' (duration: 105.423003ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:22:23.610025Z","caller":"traceutil/trace.go:171","msg":"trace[1910773463] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"180.528327ms","start":"2026-03-01T13:22:23.429466Z","end":"2026-03-01T13:22:23.609995Z","steps":["trace[1910773463] 'process raft request' (duration: 180.449305ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:22:23.610037Z","caller":"traceutil/trace.go:171","msg":"trace[1249166452] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"181.851856ms","start":"2026-03-01T13:22:23.428146Z","end":"2026-03-01T13:22:23.609998Z","steps":["trace[1249166452] 'process raft request' (duration: 92.84505ms)","trace[1249166452] 'compare' (duration: 88.845123ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:24:07.987121Z","caller":"traceutil/trace.go:171","msg":"trace[1224500847] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"121.642113ms","start":"2026-03-01T13:24:07.865445Z","end":"2026-03-01T13:24:07.987087Z","steps":["trace[1224500847] 'process raft request' (duration: 121.468229ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:24:08.466984Z","caller":"traceutil/trace.go:171","msg":"trace[1898886828] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"119.451875ms","start":"2026-03-01T13:24:08.347505Z","end":"2026-03-01T13:24:08.466957Z","steps":["trace[1898886828] 'process raft request' (duration: 119.301331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:24:16.790209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.49318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:24:16.790327Z","caller":"traceutil/trace.go:171","msg":"trace[716874608] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1959; }","duration":"537.61429ms","start":"2026-03-01T13:24:16.252698Z","end":"2026-03-01T13:24:16.790312Z","steps":["trace[716874608] 'read index received' (duration: 36.801µs)","trace[716874608] 'applied index is now lower than readState.Index' (duration: 537.576199ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:24:16.790546Z","caller":"traceutil/trace.go:171","msg":"trace[1734980261] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"675.708829ms","start":"2026-03-01T13:24:16.114825Z","end":"2026-03-01T13:24:16.790534Z","steps":["trace[1734980261] 'process raft request' (duration: 68.816369ms)","trace[1734980261] 'compare' (duration: 606.394018ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:24:16.790635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:24:16.114809Z","time spent":"675.78381ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:24:16.791025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.301416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-01T13:24:16.791072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.234844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T13:24:16.791097Z","caller":"traceutil/trace.go:171","msg":"trace[622469991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1872; }","duration":"538.421109ms","start":"2026-03-01T13:24:16.252658Z","end":"2026-03-01T13:24:16.791079Z","steps":["trace[622469991] 'agreement among raft nodes before linearized reading' (duration: 538.215004ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:24:16.791109Z","caller":"traceutil/trace.go:171","msg":"trace[991971312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1872; }","duration":"538.290176ms","start":"2026-03-01T13:24:16.252806Z","end":"2026-03-01T13:24:16.791096Z","steps":["trace[991971312] 'agreement among raft nodes before linearized reading' (duration: 538.170634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:24:16.791139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:24:16.252794Z","time spent":"538.339517ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T13:24:16.791137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:24:16.25264Z","time spent":"538.4897ms","remote":"127.0.0.1:55660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T13:24:16.791173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.608879ms","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-03-01T13:24:16.791209Z","caller":"traceutil/trace.go:171","msg":"trace[1359701615] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1872; }","duration":"424.69061ms","start":"2026-03-01T13:24:16.366507Z","end":"2026-03-01T13:24:16.791198Z","steps":["trace[1359701615] 'agreement among raft nodes before linearized reading' (duration: 424.626769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:24:16.791232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:24:16.366487Z","time spent":"424.736361ms","remote":"127.0.0.1:49448","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-03-01T13:24:16.791312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.548493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-01T13:24:16.791806Z","caller":"traceutil/trace.go:171","msg":"trace[1635683835] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1872; }","duration":"244.090445ms","start":"2026-03-01T13:24:16.547692Z","end":"2026-03-01T13:24:16.791782Z","steps":["trace[1635683835] 'agreement among raft nodes before linearized reading' (duration: 243.4132ms)"],"step_count":1}