{"level":"warn","ts":"2026-02-14T04:56:08.458668Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-14T04:56:08.459029Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-14T04:56:08.459213Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-14T04:56:08.469532Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-14T04:56:08.469569Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-14T04:56:08.470414Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-14T04:56:08.470542Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-14T04:56:08.476969Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.139832ms"} {"level":"info","ts":"2026-02-14T04:56:08.499594Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-14T04:56:08.49981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-14T04:56:08.499879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-14T04:56:08.499974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-14T04:56:08.500031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-14T04:56:08.500146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-14T04:56:08.511506Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-14T04:56:08.513488Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-14T04:56:08.514897Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-14T04:56:08.516914Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-14T04:56:08.519201Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-14T04:56:08.519582Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-14T04:56:08.519721Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-14T04:56:08.519761Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-14T04:56:08.530686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-14T04:56:08.535917Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-14T04:56:08.536349Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-14T04:56:08.536397Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-14T04:56:08.535941Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-14T04:56:08.536446Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-14T04:56:08.537478Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-14T04:56:08.703749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-14T04:56:08.703883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-14T04:56:08.703916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-14T04:56:08.703962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-14T04:56:08.703985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-14T04:56:08.704007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-14T04:56:08.704056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-14T04:56:08.708258Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-14T04:56:08.708511Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:56:08.708598Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T04:56:08.709118Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-14T04:56:08.709175Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-14T04:56:08.709396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:56:08.715489Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-14T04:56:08.716639Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:56:08.71813Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:56:08.718158Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T04:56:08.718511Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-14T04:56:14.758432Z","caller":"traceutil/trace.go:171","msg":"trace[1935830589] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"125.516567ms","start":"2026-02-14T04:56:14.632882Z","end":"2026-02-14T04:56:14.758399Z","steps":["trace[1935830589] 'process raft request' (duration: 35.597584ms)","trace[1935830589] 'compare' (duration: 89.75379ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:14.765045Z","caller":"traceutil/trace.go:171","msg":"trace[1482380725] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"111.703244ms","start":"2026-02-14T04:56:14.653304Z","end":"2026-02-14T04:56:14.765008Z","steps":["trace[1482380725] 'process raft request' (duration: 111.538281ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:20.051029Z","caller":"traceutil/trace.go:171","msg":"trace[1096865731] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"223.784345ms","start":"2026-02-14T04:56:19.827219Z","end":"2026-02-14T04:56:20.051003Z","steps":["trace[1096865731] 'process raft request' (duration: 221.88611ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:20.541128Z","caller":"traceutil/trace.go:171","msg":"trace[10590697] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"476.678525ms","start":"2026-02-14T04:56:20.064419Z","end":"2026-02-14T04:56:20.541097Z","steps":["trace[10590697] 'process raft request' (duration: 473.279313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:20.542816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:20.064399Z","time spent":"477.865626ms","remote":"127.0.0.1:45388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:20.543217Z","caller":"traceutil/trace.go:171","msg":"trace[1159605707] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"486.871343ms","start":"2026-02-14T04:56:20.056336Z","end":"2026-02-14T04:56:20.543207Z","steps":["trace[1159605707] 'process raft request' (duration: 481.27259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:20.543444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:20.056326Z","time spent":"487.091017ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:56:20.539205Z","caller":"traceutil/trace.go:171","msg":"trace[1343806209] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"483.661194ms","start":"2026-02-14T04:56:20.055516Z","end":"2026-02-14T04:56:20.539177Z","steps":["trace[1343806209] 'process raft request' (duration: 475.136037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:20.544214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:20.055493Z","time spent":"488.456212ms","remote":"127.0.0.1:45388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:56:20.545049Z","caller":"traceutil/trace.go:171","msg":"trace[1516637245] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"484.115152ms","start":"2026-02-14T04:56:20.059553Z","end":"2026-02-14T04:56:20.543668Z","steps":["trace[1516637245] 'process raft request' (duration: 478.111162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:20.54533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:20.05953Z","time spent":"485.764632ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:56:39.615906Z","caller":"traceutil/trace.go:171","msg":"trace[653835373] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"197.143185ms","start":"2026-02-14T04:56:39.418739Z","end":"2026-02-14T04:56:39.615883Z","steps":["trace[653835373] 'read index received' (duration: 196.968502ms)","trace[653835373] 'applied index is now lower than readState.Index' (duration: 174.043µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:39.616061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.288828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-14T04:56:39.616103Z","caller":"traceutil/trace.go:171","msg":"trace[2129143064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"197.37345ms","start":"2026-02-14T04:56:39.418722Z","end":"2026-02-14T04:56:39.616095Z","steps":["trace[2129143064] 'agreement among raft nodes before linearized reading' (duration: 197.256588ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:39.616362Z","caller":"traceutil/trace.go:171","msg":"trace[1668291306] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"205.301515ms","start":"2026-02-14T04:56:39.411053Z","end":"2026-02-14T04:56:39.616354Z","steps":["trace[1668291306] 'process raft request' (duration: 204.714824ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:39.992888Z","caller":"traceutil/trace.go:171","msg":"trace[2111476628] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"305.751032ms","start":"2026-02-14T04:56:39.687109Z","end":"2026-02-14T04:56:39.99286Z","steps":["trace[2111476628] 'read index received' (duration: 258.671686ms)","trace[2111476628] 'applied index is now lower than readState.Index' (duration: 47.078316ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:39.993217Z","caller":"traceutil/trace.go:171","msg":"trace[238502460] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"370.101925ms","start":"2026-02-14T04:56:39.623101Z","end":"2026-02-14T04:56:39.993203Z","steps":["trace[238502460] 'process raft request' (duration: 322.754875ms)","trace[238502460] 'compare' (duration: 46.884542ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:39.993343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:39.62308Z","time spent":"370.170737ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:56:39.993542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.436505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:39.993576Z","caller":"traceutil/trace.go:171","msg":"trace[1013847520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"306.509486ms","start":"2026-02-14T04:56:39.687056Z","end":"2026-02-14T04:56:39.993566Z","steps":["trace[1013847520] 'agreement among raft nodes before linearized reading' (duration: 306.452805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:39.993603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:39.687032Z","time spent":"306.565517ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:56:39.993754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.525164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-14T04:56:39.99378Z","caller":"traceutil/trace.go:171","msg":"trace[510648432] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:518; }","duration":"190.604155ms","start":"2026-02-14T04:56:39.803168Z","end":"2026-02-14T04:56:39.993772Z","steps":["trace[510648432] 'agreement among raft nodes before linearized reading' (duration: 190.542184ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:40.78727Z","caller":"traceutil/trace.go:171","msg":"trace[836676270] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"123.936599ms","start":"2026-02-14T04:56:40.663305Z","end":"2026-02-14T04:56:40.787242Z","steps":["trace[836676270] 'process raft request' (duration: 123.784576ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:41.907625Z","caller":"traceutil/trace.go:171","msg":"trace[624990679] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"276.979553ms","start":"2026-02-14T04:56:41.63063Z","end":"2026-02-14T04:56:41.90761Z","steps":["trace[624990679] 'process raft request' (duration: 276.882751ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:41.907997Z","caller":"traceutil/trace.go:171","msg":"trace[1039728843] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:539; }","duration":"224.040539ms","start":"2026-02-14T04:56:41.683947Z","end":"2026-02-14T04:56:41.907987Z","steps":["trace[1039728843] 'read index received' (duration: 224.036959ms)","trace[1039728843] 'applied index is now lower than readState.Index' (duration: 2.83µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:41.908061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.10031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:41.908081Z","caller":"traceutil/trace.go:171","msg":"trace[856606866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"224.159112ms","start":"2026-02-14T04:56:41.683914Z","end":"2026-02-14T04:56:41.908073Z","steps":["trace[856606866] 'agreement among raft nodes before linearized reading' (duration: 224.113001ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:42.044679Z","caller":"traceutil/trace.go:171","msg":"trace[2128520599] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"135.744456ms","start":"2026-02-14T04:56:41.908912Z","end":"2026-02-14T04:56:42.044657Z","steps":["trace[2128520599] 'read index received' (duration: 55.050882ms)","trace[2128520599] 'applied index is now lower than readState.Index' (duration: 80.692574ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:42.044895Z","caller":"traceutil/trace.go:171","msg":"trace[1205888609] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"150.890985ms","start":"2026-02-14T04:56:41.893996Z","end":"2026-02-14T04:56:42.044887Z","steps":["trace[1205888609] 'process raft request' (duration: 69.950267ms)","trace[1205888609] 'compare' (duration: 80.599951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:42.045065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.133573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:42.045085Z","caller":"traceutil/trace.go:171","msg":"trace[1789958687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"136.189935ms","start":"2026-02-14T04:56:41.908889Z","end":"2026-02-14T04:56:42.045079Z","steps":["trace[1789958687] 'agreement among raft nodes before linearized reading' (duration: 136.129363ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:42.98017Z","caller":"traceutil/trace.go:171","msg":"trace[804426373] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"184.739168ms","start":"2026-02-14T04:56:42.79541Z","end":"2026-02-14T04:56:42.980149Z","steps":["trace[804426373] 'read index received' (duration: 184.587205ms)","trace[804426373] 'applied index is now lower than readState.Index' (duration: 150.873µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:42.980387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.952471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T04:56:42.980426Z","caller":"traceutil/trace.go:171","msg":"trace[47801651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"185.016042ms","start":"2026-02-14T04:56:42.795402Z","end":"2026-02-14T04:56:42.980418Z","steps":["trace[47801651] 'agreement among raft nodes before linearized reading' (duration: 184.852569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:45.776271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.995299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-14T04:56:45.776345Z","caller":"traceutil/trace.go:171","msg":"trace[1449686033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"584.118681ms","start":"2026-02-14T04:56:45.19221Z","end":"2026-02-14T04:56:45.776328Z","steps":["trace[1449686033] 'range keys from in-memory index tree' (duration: 583.838956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:45.776373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:45.19219Z","time spent":"584.177452ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:56:47.557377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.31499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:56:47.557474Z","caller":"traceutil/trace.go:171","msg":"trace[408459830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"525.466993ms","start":"2026-02-14T04:56:47.031985Z","end":"2026-02-14T04:56:47.557452Z","steps":["trace[408459830] 'range keys from in-memory index tree' (duration: 525.114026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:47.55752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:47.031955Z","time spent":"525.553835ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-14T04:56:48.07808Z","caller":"traceutil/trace.go:171","msg":"trace[1466008810] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"390.97158ms","start":"2026-02-14T04:56:47.687084Z","end":"2026-02-14T04:56:48.078055Z","steps":["trace[1466008810] 'read index received' (duration: 390.870908ms)","trace[1466008810] 'applied index is now lower than readState.Index' (duration: 99.412µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:48.078196Z","caller":"traceutil/trace.go:171","msg":"trace[1063335819] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"516.735772ms","start":"2026-02-14T04:56:47.56145Z","end":"2026-02-14T04:56:48.078186Z","steps":["trace[1063335819] 'process raft request' (duration: 516.444937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:48.078273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.183473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-14T04:56:48.078315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:47.561426Z","time spent":"516.794863ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:56:48.078361Z","caller":"traceutil/trace.go:171","msg":"trace[613945907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"391.285096ms","start":"2026-02-14T04:56:47.687059Z","end":"2026-02-14T04:56:48.078344Z","steps":["trace[613945907] 'agreement among raft nodes before linearized reading' (duration: 391.155364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:48.078395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:47.687013Z","time spent":"391.376197ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T04:56:48.189346Z","caller":"traceutil/trace.go:171","msg":"trace[470955467] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"111.157454ms","start":"2026-02-14T04:56:48.078158Z","end":"2026-02-14T04:56:48.189316Z","steps":["trace[470955467] 'read index received' (duration: 94.546338ms)","trace[470955467] 'applied index is now lower than readState.Index' (duration: 16.609716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:48.189558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.46595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-14T04:56:48.189637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.338268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-14T04:56:48.189642Z","caller":"traceutil/trace.go:171","msg":"trace[545576209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"260.634013ms","start":"2026-02-14T04:56:47.928986Z","end":"2026-02-14T04:56:48.18962Z","steps":["trace[545576209] 'agreement among raft nodes before linearized reading' (duration: 260.350488ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:48.189665Z","caller":"traceutil/trace.go:171","msg":"trace[1354293113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:538; }","duration":"390.425629ms","start":"2026-02-14T04:56:47.799231Z","end":"2026-02-14T04:56:48.189657Z","steps":["trace[1354293113] 'agreement among raft nodes before linearized reading' (duration: 390.232655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:48.189694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:47.799216Z","time spent":"390.472069ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:56:48.189924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.815395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-14T04:56:48.189945Z","caller":"traceutil/trace.go:171","msg":"trace[643995556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"112.892077ms","start":"2026-02-14T04:56:48.077046Z","end":"2026-02-14T04:56:48.189938Z","steps":["trace[643995556] 'agreement among raft nodes before linearized reading' (duration: 112.831556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:48.189949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.060107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g5h8r\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-14T04:56:48.189972Z","caller":"traceutil/trace.go:171","msg":"trace[130972115] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g5h8r; range_end:; response_count:1; response_revision:538; }","duration":"475.113797ms","start":"2026-02-14T04:56:47.714852Z","end":"2026-02-14T04:56:48.189965Z","steps":["trace[130972115] 'agreement among raft nodes before linearized reading' (duration: 474.939173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:48.189991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:47.714839Z","time spent":"475.147438ms","remote":"127.0.0.1:45388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-g5h8r\" "} {"level":"warn","ts":"2026-02-14T04:56:48.190252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.450735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:48.190275Z","caller":"traceutil/trace.go:171","msg":"trace[1143994901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"108.499936ms","start":"2026-02-14T04:56:48.081768Z","end":"2026-02-14T04:56:48.190268Z","steps":["trace[1143994901] 'agreement among raft nodes before linearized reading' (duration: 108.464985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.325116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.47212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:50.325186Z","caller":"traceutil/trace.go:171","msg":"trace[1935360864] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"384.598463ms","start":"2026-02-14T04:56:49.940574Z","end":"2026-02-14T04:56:50.325172Z","steps":["trace[1935360864] 'range keys from in-memory index tree' (duration: 384.44212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.325224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.940568Z","time spent":"384.650343ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:56:50.325313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.751824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:50.325359Z","caller":"traceutil/trace.go:171","msg":"trace[648190906] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"385.802765ms","start":"2026-02-14T04:56:49.939543Z","end":"2026-02-14T04:56:50.325346Z","steps":["trace[648190906] 'range keys from in-memory index tree' (duration: 385.721003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.32538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.939539Z","time spent":"385.836925ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:56:50.325407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.916647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:50.325425Z","caller":"traceutil/trace.go:171","msg":"trace[633290989] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"385.953197ms","start":"2026-02-14T04:56:49.939467Z","end":"2026-02-14T04:56:50.32542Z","steps":["trace[633290989] 'range keys from in-memory index tree' (duration: 385.819845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.325438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.939457Z","time spent":"385.977877ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:56:50.325628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.027971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:56:50.325661Z","caller":"traceutil/trace.go:171","msg":"trace[1075818826] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"375.370652ms","start":"2026-02-14T04:56:49.950285Z","end":"2026-02-14T04:56:50.325655Z","steps":["trace[1075818826] 'read index received' (duration: 152.23794ms)","trace[1075818826] 'applied index is now lower than readState.Index' (duration: 223.131982ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:56:50.325717Z","caller":"traceutil/trace.go:171","msg":"trace[1518210879] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"382.944492ms","start":"2026-02-14T04:56:49.942765Z","end":"2026-02-14T04:56:50.325709Z","steps":["trace[1518210879] 'process raft request' (duration: 159.726138ms)","trace[1518210879] 'compare' (duration: 222.709835ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:56:50.325755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.469984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:50.325772Z","caller":"traceutil/trace.go:171","msg":"trace[1467478558] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:557; }","duration":"375.498735ms","start":"2026-02-14T04:56:49.950267Z","end":"2026-02-14T04:56:50.325766Z","steps":["trace[1467478558] 'agreement among raft nodes before linearized reading' (duration: 375.474825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.325787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.950259Z","time spent":"375.523655ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:56:50.325788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.942746Z","time spent":"382.989953ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:56:50.383356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.970341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:50.383482Z","caller":"traceutil/trace.go:171","msg":"trace[1946020322] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"433.091453ms","start":"2026-02-14T04:56:49.950344Z","end":"2026-02-14T04:56:50.383435Z","steps":["trace[1946020322] 'agreement among raft nodes before linearized reading' (duration: 432.831319ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:50.383504Z","caller":"traceutil/trace.go:171","msg":"trace[1644455275] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"304.538039ms","start":"2026-02-14T04:56:50.078953Z","end":"2026-02-14T04:56:50.383491Z","steps":["trace[1644455275] 'process raft request' (duration: 304.103242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.383552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:50.078935Z","time spent":"304.59397ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-14T04:56:50.383529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.95034Z","time spent":"433.179435ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:56:50.383747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.264497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-14T04:56:50.383886Z","caller":"traceutil/trace.go:171","msg":"trace[76711657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"166.42542ms","start":"2026-02-14T04:56:50.217453Z","end":"2026-02-14T04:56:50.383879Z","steps":["trace[76711657] 'agreement among raft nodes before linearized reading' (duration: 166.252856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.383983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.902994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-14T04:56:50.384035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.18049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:56:50.384057Z","caller":"traceutil/trace.go:171","msg":"trace[1955371799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"176.243351ms","start":"2026-02-14T04:56:50.207808Z","end":"2026-02-14T04:56:50.384051Z","steps":["trace[1955371799] 'agreement among raft nodes before linearized reading' (duration: 176.18357ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:50.384048Z","caller":"traceutil/trace.go:171","msg":"trace[405373808] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"275.984045ms","start":"2026-02-14T04:56:50.108052Z","end":"2026-02-14T04:56:50.384036Z","steps":["trace[405373808] 'agreement among raft nodes before linearized reading' (duration: 275.893133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.384737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.358797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:50.384781Z","caller":"traceutil/trace.go:171","msg":"trace[1689816294] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"434.410858ms","start":"2026-02-14T04:56:49.950363Z","end":"2026-02-14T04:56:50.384774Z","steps":["trace[1689816294] 'agreement among raft nodes before linearized reading' (duration: 434.353417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.384802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.950359Z","time spent":"434.437658ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:56:50.385356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.818739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:56:50.385411Z","caller":"traceutil/trace.go:171","msg":"trace[1426032646] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"432.903741ms","start":"2026-02-14T04:56:49.952499Z","end":"2026-02-14T04:56:50.385403Z","steps":["trace[1426032646] 'agreement among raft nodes before linearized reading' (duration: 432.756939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.385436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.952492Z","time spent":"432.935342ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-14T04:56:50.385517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.107991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-14T04:56:50.385361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.314435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-14T04:56:50.385574Z","caller":"traceutil/trace.go:171","msg":"trace[540618344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"292.56727ms","start":"2026-02-14T04:56:50.092998Z","end":"2026-02-14T04:56:50.385565Z","steps":["trace[540618344] 'agreement among raft nodes before linearized reading' (duration: 291.072283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.383403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.860424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-14T04:56:50.385837Z","caller":"traceutil/trace.go:171","msg":"trace[897375003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"178.329049ms","start":"2026-02-14T04:56:50.207501Z","end":"2026-02-14T04:56:50.38583Z","steps":["trace[897375003] 'agreement among raft nodes before linearized reading' (duration: 175.796392ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:56:50.385544Z","caller":"traceutil/trace.go:171","msg":"trace[232609348] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:558; }","duration":"435.140071ms","start":"2026-02-14T04:56:49.950394Z","end":"2026-02-14T04:56:50.385534Z","steps":["trace[232609348] 'agreement among raft nodes before linearized reading' (duration: 435.104811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:56:50.387539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:56:49.95039Z","time spent":"437.138038ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-14T04:57:02.800811Z","caller":"traceutil/trace.go:171","msg":"trace[1956533670] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"314.003934ms","start":"2026-02-14T04:57:02.486791Z","end":"2026-02-14T04:57:02.800795Z","steps":["trace[1956533670] 'read index received' (duration: 278.008432ms)","trace[1956533670] 'applied index is now lower than readState.Index' (duration: 35.994752ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:02.800895Z","caller":"traceutil/trace.go:171","msg":"trace[997981042] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"325.955834ms","start":"2026-02-14T04:57:02.474934Z","end":"2026-02-14T04:57:02.80089Z","steps":["trace[997981042] 'process raft request' (duration: 289.940181ms)","trace[997981042] 'compare' (duration: 35.84844ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:02.800958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:02.474926Z","time spent":"325.982414ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:02.801016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.789574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-14T04:57:02.801073Z","caller":"traceutil/trace.go:171","msg":"trace[43987531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:706; }","duration":"298.863645ms","start":"2026-02-14T04:57:02.502197Z","end":"2026-02-14T04:57:02.801061Z","steps":["trace[43987531] 'agreement among raft nodes before linearized reading' (duration: 298.725812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:02.801235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.999043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-14T04:57:02.801245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.451562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T04:57:02.801252Z","caller":"traceutil/trace.go:171","msg":"trace[542417664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"290.024623ms","start":"2026-02-14T04:57:02.511222Z","end":"2026-02-14T04:57:02.801246Z","steps":["trace[542417664] 'agreement among raft nodes before linearized reading' (duration: 289.965462ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:02.801267Z","caller":"traceutil/trace.go:171","msg":"trace[670801622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:706; }","duration":"314.482412ms","start":"2026-02-14T04:57:02.486777Z","end":"2026-02-14T04:57:02.801259Z","steps":["trace[670801622] 'agreement among raft nodes before linearized reading' (duration: 314.437052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:02.801281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:02.486759Z","time spent":"314.519233ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:57:10.697094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010133634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-14T04:57:10.697156Z","caller":"traceutil/trace.go:171","msg":"trace[303949230] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.010237355s","start":"2026-02-14T04:57:08.686909Z","end":"2026-02-14T04:57:10.697146Z","steps":["trace[303949230] 'agreement among raft nodes before linearized reading' (duration: 2.010159694s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:10.697206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:08.686895Z","time spent":"2.010305027s","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/14 04:57:10 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-14T04:57:11.718807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.039070704s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T04:57:11.718911Z","caller":"traceutil/trace.go:171","msg":"trace[17996632] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"3.2585886s","start":"2026-02-14T04:57:08.460304Z","end":"2026-02-14T04:57:11.718893Z","steps":["trace[17996632] 'read index received' (duration: 219.437695ms)","trace[17996632] 'applied index is now lower than readState.Index' (duration: 3.039149525s)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:11.719471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.242632599s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:11.719589Z","caller":"traceutil/trace.go:171","msg":"trace[759727799] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:723; }","duration":"2.242761052s","start":"2026-02-14T04:57:09.476808Z","end":"2026-02-14T04:57:11.719569Z","steps":["trace[759727799] 'range keys from in-memory index tree' (duration: 2.242613459s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:11.72302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.882472344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-14T04:57:11.723096Z","caller":"traceutil/trace.go:171","msg":"trace[901498156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:723; }","duration":"2.882578786s","start":"2026-02-14T04:57:08.840504Z","end":"2026-02-14T04:57:11.723083Z","steps":["trace[901498156] 'agreement among raft nodes before linearized reading' (duration: 2.882400913s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:11.723387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:08.840492Z","time spent":"2.882819611s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:57:11.723681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026384564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:11.723707Z","caller":"traceutil/trace.go:171","msg":"trace[156219574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"1.026425665s","start":"2026-02-14T04:57:10.697275Z","end":"2026-02-14T04:57:11.7237Z","steps":["trace[156219574] 'agreement among raft nodes before linearized reading' (duration: 1.026382264s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:11.723765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:10.697265Z","time spent":"1.026456246s","remote":"127.0.0.1:45236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:11.723958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.847244028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:57:11.72399Z","caller":"traceutil/trace.go:171","msg":"trace[835362166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:723; }","duration":"2.847290778s","start":"2026-02-14T04:57:08.876691Z","end":"2026-02-14T04:57:11.723982Z","steps":["trace[835362166] 'agreement among raft nodes before linearized reading' (duration: 2.847213617s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:11.724009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:08.876685Z","time spent":"2.847319989s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T04:57:11.724239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.847724726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-14T04:57:11.724269Z","caller":"traceutil/trace.go:171","msg":"trace[1613615489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"2.847783557s","start":"2026-02-14T04:57:08.876479Z","end":"2026-02-14T04:57:11.724263Z","steps":["trace[1613615489] 'agreement among raft nodes before linearized reading' (duration: 2.847711616s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:11.724331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:08.876465Z","time spent":"2.847857828s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T04:57:11.724524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.88163716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-14T04:57:11.724552Z","caller":"traceutil/trace.go:171","msg":"trace[248206616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:723; }","duration":"2.8816947s","start":"2026-02-14T04:57:08.84285Z","end":"2026-02-14T04:57:11.724545Z","steps":["trace[248206616] 'agreement among raft nodes before linearized reading' (duration: 2.881626089s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:11.72462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:08.842831Z","time spent":"2.881781862s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T04:57:11.72224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.261926023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-14T04:57:11.726757Z","caller":"traceutil/trace.go:171","msg":"trace[709685058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"3.266453076s","start":"2026-02-14T04:57:08.460293Z","end":"2026-02-14T04:57:11.726746Z","steps":["trace[709685058] 'agreement among raft nodes before linearized reading' (duration: 3.261014016s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:11.726843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:08.460278Z","time spent":"3.266555487s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-14T04:57:11.982967Z","caller":"traceutil/trace.go:171","msg":"trace[1936596279] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"252.074095ms","start":"2026-02-14T04:57:11.73087Z","end":"2026-02-14T04:57:11.982944Z","steps":["trace[1936596279] 'process raft request' (duration: 251.931632ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:12.144386Z","caller":"traceutil/trace.go:171","msg":"trace[1358759212] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"411.17911ms","start":"2026-02-14T04:57:11.733188Z","end":"2026-02-14T04:57:12.144367Z","steps":["trace[1358759212] 'process raft request' (duration: 411.121459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:12.144517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:11.73317Z","time spent":"411.294923ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-14T04:57:12.144715Z","caller":"traceutil/trace.go:171","msg":"trace[1002622564] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"412.908503ms","start":"2026-02-14T04:57:11.731797Z","end":"2026-02-14T04:57:12.144705Z","steps":["trace[1002622564] 'process raft request' (duration: 412.375893ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:12.144721Z","caller":"traceutil/trace.go:171","msg":"trace[609022258] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"411.156841ms","start":"2026-02-14T04:57:11.733544Z","end":"2026-02-14T04:57:12.144701Z","steps":["trace[609022258] 'process raft request' (duration: 410.795364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:12.14476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:11.73179Z","time spent":"412.941374ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:12.144827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:11.733538Z","time spent":"411.227922ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:12.145079Z","caller":"traceutil/trace.go:171","msg":"trace[2078244960] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:748; }","duration":"411.345205ms","start":"2026-02-14T04:57:11.733727Z","end":"2026-02-14T04:57:12.145072Z","steps":["trace[2078244960] 'read index received' (duration: 249.717462ms)","trace[2078244960] 'applied index is now lower than readState.Index' (duration: 161.627143ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:12.145084Z","caller":"traceutil/trace.go:171","msg":"trace[1511868737] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"410.288195ms","start":"2026-02-14T04:57:11.734788Z","end":"2026-02-14T04:57:12.145076Z","steps":["trace[1511868737] 'process raft request' (duration: 409.854866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:12.145128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:11.734782Z","time spent":"410.323335ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:12.145186Z","caller":"traceutil/trace.go:171","msg":"trace[261274014] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"409.758656ms","start":"2026-02-14T04:57:11.735421Z","end":"2026-02-14T04:57:12.14518Z","steps":["trace[261274014] 'process raft request' (duration: 409.592703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:12.14523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:11.735416Z","time spent":"409.781816ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:12.14529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.561488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:12.145309Z","caller":"traceutil/trace.go:171","msg":"trace[1893641423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"411.587889ms","start":"2026-02-14T04:57:11.733715Z","end":"2026-02-14T04:57:12.145303Z","steps":["trace[1893641423] 'agreement among raft nodes before linearized reading' (duration: 411.557588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:12.145325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:11.73371Z","time spent":"411.611999ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T04:57:12.713786Z","caller":"traceutil/trace.go:171","msg":"trace[1520639757] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"183.145778ms","start":"2026-02-14T04:57:12.530622Z","end":"2026-02-14T04:57:12.713768Z","steps":["trace[1520639757] 'process raft request' (duration: 183.026736ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:26.351757Z","caller":"traceutil/trace.go:171","msg":"trace[847239856] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"142.627957ms","start":"2026-02-14T04:57:26.209109Z","end":"2026-02-14T04:57:26.351737Z","steps":["trace[847239856] 'process raft request' (duration: 142.520625ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:26.357536Z","caller":"traceutil/trace.go:171","msg":"trace[499324651] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:799; }","duration":"145.98607ms","start":"2026-02-14T04:57:26.211541Z","end":"2026-02-14T04:57:26.357528Z","steps":["trace[499324651] 'read index received' (duration: 140.036222ms)","trace[499324651] 'applied index is now lower than readState.Index' (duration: 5.949468ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:26.357721Z","caller":"traceutil/trace.go:171","msg":"trace[1035781177] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"147.359105ms","start":"2026-02-14T04:57:26.210356Z","end":"2026-02-14T04:57:26.357715Z","steps":["trace[1035781177] 'process raft request' (duration: 147.109261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:26.357926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.373567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-14T04:57:26.358071Z","caller":"traceutil/trace.go:171","msg":"trace[1083890779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:774; }","duration":"146.505229ms","start":"2026-02-14T04:57:26.211509Z","end":"2026-02-14T04:57:26.358014Z","steps":["trace[1083890779] 'agreement among raft nodes before linearized reading' (duration: 146.356807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:26.358254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.805025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-14T04:57:26.358301Z","caller":"traceutil/trace.go:171","msg":"trace[760610625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:774; }","duration":"145.864607ms","start":"2026-02-14T04:57:26.212431Z","end":"2026-02-14T04:57:26.358296Z","steps":["trace[760610625] 'agreement among raft nodes before linearized reading' (duration: 145.732565ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:30.63402Z","caller":"traceutil/trace.go:171","msg":"trace[459528469] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"385.463268ms","start":"2026-02-14T04:57:30.248541Z","end":"2026-02-14T04:57:30.634004Z","steps":["trace[459528469] 'read index received' (duration: 385.180913ms)","trace[459528469] 'applied index is now lower than readState.Index' (duration: 281.645µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:30.635611Z","caller":"traceutil/trace.go:171","msg":"trace[1155680892] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"579.611588ms","start":"2026-02-14T04:57:30.055989Z","end":"2026-02-14T04:57:30.6356Z","steps":["trace[1155680892] 'process raft request' (duration: 577.883047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:30.635705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:30.055972Z","time spent":"579.668379ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:30.63586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.318031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:30.635883Z","caller":"traceutil/trace.go:171","msg":"trace[747042693] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:782; }","duration":"387.380162ms","start":"2026-02-14T04:57:30.248496Z","end":"2026-02-14T04:57:30.635876Z","steps":["trace[747042693] 'agreement among raft nodes before linearized reading' (duration: 387.340251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:30.635903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:30.248478Z","time spent":"387.419862ms","remote":"127.0.0.1:45578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-14T04:57:30.63649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.850474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-14T04:57:30.636508Z","caller":"traceutil/trace.go:171","msg":"trace[1801229606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"266.926676ms","start":"2026-02-14T04:57:30.369577Z","end":"2026-02-14T04:57:30.636503Z","steps":["trace[1801229606] 'agreement among raft nodes before linearized reading' (duration: 266.846734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:30.636915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.364093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:57:30.63695Z","caller":"traceutil/trace.go:171","msg":"trace[1069126579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"257.410693ms","start":"2026-02-14T04:57:30.379534Z","end":"2026-02-14T04:57:30.636944Z","steps":["trace[1069126579] 'agreement among raft nodes before linearized reading' (duration: 257.344052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:30.637199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.610767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-14T04:57:30.63723Z","caller":"traceutil/trace.go:171","msg":"trace[1978974447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"262.654797ms","start":"2026-02-14T04:57:30.37457Z","end":"2026-02-14T04:57:30.637225Z","steps":["trace[1978974447] 'agreement among raft nodes before linearized reading' (duration: 262.591516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:30.637432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.244946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-14T04:57:30.637451Z","caller":"traceutil/trace.go:171","msg":"trace[1476542167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"264.285286ms","start":"2026-02-14T04:57:30.37316Z","end":"2026-02-14T04:57:30.637446Z","steps":["trace[1476542167] 'agreement among raft nodes before linearized reading' (duration: 264.216035ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:30.961983Z","caller":"traceutil/trace.go:171","msg":"trace[1926744768] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"319.918293ms","start":"2026-02-14T04:57:30.642043Z","end":"2026-02-14T04:57:30.961961Z","steps":["trace[1926744768] 'process raft request' (duration: 316.922039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:30.962163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:30.64203Z","time spent":"320.016205ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:31.663042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.443034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T04:57:31.663248Z","caller":"traceutil/trace.go:171","msg":"trace[1818134658] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:810; }","duration":"978.453549ms","start":"2026-02-14T04:57:30.684778Z","end":"2026-02-14T04:57:31.663231Z","steps":["trace[1818134658] 'read index received' (duration: 274.197897ms)","trace[1818134658] 'applied index is now lower than readState.Index' (duration: 704.254672ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:31.663519Z","caller":"traceutil/trace.go:171","msg":"trace[1897546569] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"1.021384413s","start":"2026-02-14T04:57:30.642122Z","end":"2026-02-14T04:57:31.663506Z","steps":["trace[1897546569] 'process raft request' (duration: 794.40305ms)","trace[1897546569] 'compare' (duration: 226.341742ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:31.663581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:30.642118Z","time spent":"1.021430115s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:31.663759Z","caller":"traceutil/trace.go:171","msg":"trace[1400641669] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"1.020394206s","start":"2026-02-14T04:57:30.643354Z","end":"2026-02-14T04:57:31.663748Z","steps":["trace[1400641669] 'process raft request' (duration: 1.019780265s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.663808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:30.643347Z","time spent":"1.020432776s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:57:31.663949Z","caller":"traceutil/trace.go:171","msg":"trace[1353066876] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"1.020530658s","start":"2026-02-14T04:57:30.643408Z","end":"2026-02-14T04:57:31.663939Z","steps":["trace[1353066876] 'process raft request' (duration: 1.019777255s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:30.643404Z","time spent":"1.020566609s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:31.664327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.539078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:31.664367Z","caller":"traceutil/trace.go:171","msg":"trace[582506839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"979.579479ms","start":"2026-02-14T04:57:30.684774Z","end":"2026-02-14T04:57:31.664354Z","steps":["trace[582506839] 'agreement among raft nodes before linearized reading' (duration: 979.510468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.664396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:30.684747Z","time spent":"979.643201ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:31.664631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.578487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:31.664672Z","caller":"traceutil/trace.go:171","msg":"trace[1518307455] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:786; }","duration":"912.656999ms","start":"2026-02-14T04:57:30.752003Z","end":"2026-02-14T04:57:31.66466Z","steps":["trace[1518307455] 'agreement among raft nodes before linearized reading' (duration: 912.565468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:31.664702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:30.751991Z","time spent":"912.70338ms","remote":"127.0.0.1:45430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-14T04:57:31.664889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.060764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T04:57:31.664922Z","caller":"traceutil/trace.go:171","msg":"trace[894907698] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:786; }","duration":"227.132976ms","start":"2026-02-14T04:57:31.437781Z","end":"2026-02-14T04:57:31.664914Z","steps":["trace[894907698] 'agreement among raft nodes before linearized reading' (duration: 227.079295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:32.007256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.528507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T04:57:32.007339Z","caller":"traceutil/trace.go:171","msg":"trace[1867821584] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:786; }","duration":"147.66362ms","start":"2026-02-14T04:57:31.859654Z","end":"2026-02-14T04:57:32.007318Z","steps":["trace[1867821584] 'count revisions from in-memory index tree' (duration: 147.440076ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:42.896685Z","caller":"traceutil/trace.go:171","msg":"trace[1798881105] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"210.047227ms","start":"2026-02-14T04:57:42.686623Z","end":"2026-02-14T04:57:42.89667Z","steps":["trace[1798881105] 'read index received' (duration: 209.898224ms)","trace[1798881105] 'applied index is now lower than readState.Index' (duration: 148.473µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:42.896925Z","caller":"traceutil/trace.go:171","msg":"trace[717991737] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"212.659375ms","start":"2026-02-14T04:57:42.684259Z","end":"2026-02-14T04:57:42.896918Z","steps":["trace[717991737] 'process raft request' (duration: 212.304799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:42.897105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.474665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:42.897123Z","caller":"traceutil/trace.go:171","msg":"trace[1804675539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"210.525645ms","start":"2026-02-14T04:57:42.686591Z","end":"2026-02-14T04:57:42.897117Z","steps":["trace[1804675539] 'agreement among raft nodes before linearized reading' (duration: 210.485765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:42.897258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.306278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-14T04:57:42.897311Z","caller":"traceutil/trace.go:171","msg":"trace[29833667] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:866; }","duration":"129.341779ms","start":"2026-02-14T04:57:42.767926Z","end":"2026-02-14T04:57:42.897267Z","steps":["trace[29833667] 'agreement among raft nodes before linearized reading' (duration: 129.279148ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:43.026698Z","caller":"traceutil/trace.go:171","msg":"trace[1478929247] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"127.979623ms","start":"2026-02-14T04:57:42.898702Z","end":"2026-02-14T04:57:43.026682Z","steps":["trace[1478929247] 'read index received' (duration: 127.612957ms)","trace[1478929247] 'applied index is now lower than readState.Index' (duration: 365.846µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:43.026822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.106945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:43.02684Z","caller":"traceutil/trace.go:171","msg":"trace[1529353986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"128.164746ms","start":"2026-02-14T04:57:42.89867Z","end":"2026-02-14T04:57:43.026834Z","steps":["trace[1529353986] 'agreement among raft nodes before linearized reading' (duration: 128.103165ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:43.955103Z","caller":"traceutil/trace.go:171","msg":"trace[2129659576] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"210.690889ms","start":"2026-02-14T04:57:43.744393Z","end":"2026-02-14T04:57:43.955084Z","steps":["trace[2129659576] 'read index received' (duration: 210.527946ms)","trace[2129659576] 'applied index is now lower than readState.Index' (duration: 162.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:43.95533Z","caller":"traceutil/trace.go:171","msg":"trace[1099844477] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"282.684171ms","start":"2026-02-14T04:57:43.672637Z","end":"2026-02-14T04:57:43.955321Z","steps":["trace[1099844477] 'process raft request' (duration: 282.329025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:43.955514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.107486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:57:43.955536Z","caller":"traceutil/trace.go:171","msg":"trace[340574199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"211.162757ms","start":"2026-02-14T04:57:43.744366Z","end":"2026-02-14T04:57:43.955529Z","steps":["trace[340574199] 'agreement among raft nodes before linearized reading' (duration: 211.075775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:43.955617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.089987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-14T04:57:43.955664Z","caller":"traceutil/trace.go:171","msg":"trace[455708150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"195.166588ms","start":"2026-02-14T04:57:43.760484Z","end":"2026-02-14T04:57:43.955651Z","steps":["trace[455708150] 'agreement among raft nodes before linearized reading' (duration: 195.007635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:43.955805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.346049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-14T04:57:43.955828Z","caller":"traceutil/trace.go:171","msg":"trace[1815916456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"191.392371ms","start":"2026-02-14T04:57:43.764429Z","end":"2026-02-14T04:57:43.955821Z","steps":["trace[1815916456] 'agreement among raft nodes before linearized reading' (duration: 191.315079ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:44.090054Z","caller":"traceutil/trace.go:171","msg":"trace[1430602751] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"121.467435ms","start":"2026-02-14T04:57:43.968569Z","end":"2026-02-14T04:57:44.090037Z","steps":["trace[1430602751] 'process raft request' (duration: 121.436394ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:44.090324Z","caller":"traceutil/trace.go:171","msg":"trace[1005027468] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"123.33577ms","start":"2026-02-14T04:57:43.966981Z","end":"2026-02-14T04:57:44.090317Z","steps":["trace[1005027468] 'process raft request' (duration: 82.199436ms)","trace[1005027468] 'compare' (duration: 40.739096ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:45.364813Z","caller":"traceutil/trace.go:171","msg":"trace[1644175869] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"328.945787ms","start":"2026-02-14T04:57:45.035852Z","end":"2026-02-14T04:57:45.364798Z","steps":["trace[1644175869] 'read index received' (duration: 328.810865ms)","trace[1644175869] 'applied index is now lower than readState.Index' (duration: 134.412µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:45.364986Z","caller":"traceutil/trace.go:171","msg":"trace[2046749249] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"463.186293ms","start":"2026-02-14T04:57:44.901794Z","end":"2026-02-14T04:57:45.364981Z","steps":["trace[2046749249] 'process raft request' (duration: 462.925509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:45.365041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:44.901778Z","time spent":"463.222344ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:45.365172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.319103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-14T04:57:45.365189Z","caller":"traceutil/trace.go:171","msg":"trace[1343099955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:873; }","duration":"329.356484ms","start":"2026-02-14T04:57:45.035828Z","end":"2026-02-14T04:57:45.365184Z","steps":["trace[1343099955] 'agreement among raft nodes before linearized reading' (duration: 329.295893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:45.365202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:45.035815Z","time spent":"329.384344ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T04:57:45.365297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.005294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T04:57:45.365309Z","caller":"traceutil/trace.go:171","msg":"trace[2130825640] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:873; }","duration":"201.039574ms","start":"2026-02-14T04:57:45.164266Z","end":"2026-02-14T04:57:45.365305Z","steps":["trace[2130825640] 'agreement among raft nodes before linearized reading' (duration: 200.984233ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:45.490886Z","caller":"traceutil/trace.go:171","msg":"trace[1972722480] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"120.153072ms","start":"2026-02-14T04:57:45.370709Z","end":"2026-02-14T04:57:45.490862Z","steps":["trace[1972722480] 'process raft request' (duration: 116.677849ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:46.731989Z","caller":"traceutil/trace.go:171","msg":"trace[1337963745] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"112.202018ms","start":"2026-02-14T04:57:46.619766Z","end":"2026-02-14T04:57:46.731968Z","steps":["trace[1337963745] 'process raft request' (duration: 111.831511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:54.011421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762697402596264,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T04:57:54.075155Z","caller":"traceutil/trace.go:171","msg":"trace[1610462564] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"563.774602ms","start":"2026-02-14T04:57:53.511366Z","end":"2026-02-14T04:57:54.075141Z","steps":["trace[1610462564] 'read index received' (duration: 563.67596ms)","trace[1610462564] 'applied index is now lower than readState.Index' (duration: 98.042µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:54.075228Z","caller":"traceutil/trace.go:171","msg":"trace[505245369] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"630.178662ms","start":"2026-02-14T04:57:53.445044Z","end":"2026-02-14T04:57:54.075223Z","steps":["trace[505245369] 'process raft request' (duration: 630.002399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:54.075288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:53.445036Z","time spent":"630.205473ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:57:54.075387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.150659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:57:54.075436Z","caller":"traceutil/trace.go:171","msg":"trace[1310592915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"392.225361ms","start":"2026-02-14T04:57:53.683196Z","end":"2026-02-14T04:57:54.075422Z","steps":["trace[1310592915] 'agreement among raft nodes before linearized reading' (duration: 392.147899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:54.07546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:53.683182Z","time spent":"392.272731ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T04:57:54.075525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.157588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-14T04:57:54.075547Z","caller":"traceutil/trace.go:171","msg":"trace[1545480342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"564.2006ms","start":"2026-02-14T04:57:53.511338Z","end":"2026-02-14T04:57:54.075539Z","steps":["trace[1545480342] 'agreement among raft nodes before linearized reading' (duration: 564.132569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:54.075561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:57:53.511323Z","time spent":"564.2349ms","remote":"127.0.0.1:45468","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-02-14T04:57:58.256106Z","caller":"traceutil/trace.go:171","msg":"trace[685294217] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"160.507681ms","start":"2026-02-14T04:57:58.095584Z","end":"2026-02-14T04:57:58.256092Z","steps":["trace[685294217] 'process raft request' (duration: 160.42297ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:58.256426Z","caller":"traceutil/trace.go:171","msg":"trace[1572179885] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:942; }","duration":"160.618413ms","start":"2026-02-14T04:57:58.095802Z","end":"2026-02-14T04:57:58.25642Z","steps":["trace[1572179885] 'read index received' (duration: 160.616353ms)","trace[1572179885] 'applied index is now lower than readState.Index' (duration: 1.76µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:58.256519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.658446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-14T04:57:58.256535Z","caller":"traceutil/trace.go:171","msg":"trace[300506576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"159.706407ms","start":"2026-02-14T04:57:58.096823Z","end":"2026-02-14T04:57:58.25653Z","steps":["trace[300506576] 'agreement among raft nodes before linearized reading' (duration: 159.628066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:58.256663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.860458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-14T04:57:58.256677Z","caller":"traceutil/trace.go:171","msg":"trace[1442941530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"160.875169ms","start":"2026-02-14T04:57:58.095798Z","end":"2026-02-14T04:57:58.256673Z","steps":["trace[1442941530] 'agreement among raft nodes before linearized reading' (duration: 160.813356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:58.256817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.910506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-14T04:57:58.25683Z","caller":"traceutil/trace.go:171","msg":"trace[1911950201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"125.936696ms","start":"2026-02-14T04:57:58.13089Z","end":"2026-02-14T04:57:58.256827Z","steps":["trace[1911950201] 'agreement among raft nodes before linearized reading' (duration: 125.904286ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:58.506396Z","caller":"traceutil/trace.go:171","msg":"trace[466996351] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"245.683022ms","start":"2026-02-14T04:57:58.26069Z","end":"2026-02-14T04:57:58.506373Z","steps":["trace[466996351] 'process raft request' (duration: 181.98601ms)","trace[466996351] 'compare' (duration: 63.099581ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:57:58.506729Z","caller":"traceutil/trace.go:171","msg":"trace[1143509519] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"244.142343ms","start":"2026-02-14T04:57:58.262576Z","end":"2026-02-14T04:57:58.506719Z","steps":["trace[1143509519] 'process raft request' (duration: 243.641095ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:58.506805Z","caller":"traceutil/trace.go:171","msg":"trace[1653545375] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"244.009771ms","start":"2026-02-14T04:57:58.262762Z","end":"2026-02-14T04:57:58.506771Z","steps":["trace[1653545375] 'process raft request' (duration: 243.485862ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:57:58.506853Z","caller":"traceutil/trace.go:171","msg":"trace[2032331956] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"245.511867ms","start":"2026-02-14T04:57:58.261332Z","end":"2026-02-14T04:57:58.506844Z","steps":["trace[2032331956] 'process raft request' (duration: 244.840866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:57:58.655178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.880211ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T04:57:58.655243Z","caller":"traceutil/trace.go:171","msg":"trace[889916158] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:942; }","duration":"197.925938ms","start":"2026-02-14T04:57:58.457305Z","end":"2026-02-14T04:57:58.655231Z","steps":["trace[889916158] 'read index received' (duration: 46.164275ms)","trace[889916158] 'applied index is now lower than readState.Index' (duration: 151.760763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:57:58.655304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.991519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T04:57:58.655322Z","caller":"traceutil/trace.go:171","msg":"trace[608740331] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:911; }","duration":"198.01705ms","start":"2026-02-14T04:57:58.4573Z","end":"2026-02-14T04:57:58.655317Z","steps":["trace[608740331] 'agreement among raft nodes before linearized reading' (duration: 197.957728ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:00.384219Z","caller":"traceutil/trace.go:171","msg":"trace[1769336089] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"122.82245ms","start":"2026-02-14T04:58:00.26138Z","end":"2026-02-14T04:58:00.384203Z","steps":["trace[1769336089] 'process raft request' (duration: 122.729608ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:00.849985Z","caller":"traceutil/trace.go:171","msg":"trace[594013792] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"293.877343ms","start":"2026-02-14T04:58:00.556088Z","end":"2026-02-14T04:58:00.849966Z","steps":["trace[594013792] 'process raft request' (duration: 293.750361ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:00.943808Z","caller":"traceutil/trace.go:171","msg":"trace[414520975] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:949; }","duration":"254.48495ms","start":"2026-02-14T04:58:00.689303Z","end":"2026-02-14T04:58:00.943787Z","steps":["trace[414520975] 'read index received' (duration: 161.097032ms)","trace[414520975] 'applied index is now lower than readState.Index' (duration: 93.386938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:00.943936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.613043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:58:00.943967Z","caller":"traceutil/trace.go:171","msg":"trace[1344724156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"254.680004ms","start":"2026-02-14T04:58:00.689277Z","end":"2026-02-14T04:58:00.943957Z","steps":["trace[1344724156] 'agreement among raft nodes before linearized reading' (duration: 254.591943ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:00.94429Z","caller":"traceutil/trace.go:171","msg":"trace[1835439897] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"388.018095ms","start":"2026-02-14T04:58:00.556259Z","end":"2026-02-14T04:58:00.944277Z","steps":["trace[1835439897] 'process raft request' (duration: 303.224252ms)","trace[1835439897] 'compare' (duration: 84.06433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:00.944371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:00.556251Z","time spent":"388.066206ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:00.944454Z","caller":"traceutil/trace.go:171","msg":"trace[1714697733] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"385.688172ms","start":"2026-02-14T04:58:00.558758Z","end":"2026-02-14T04:58:00.944446Z","steps":["trace[1714697733] 'process raft request' (duration: 384.943359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:00.944498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:00.558699Z","time spent":"385.773863ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:00.944571Z","caller":"traceutil/trace.go:171","msg":"trace[1647927940] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"385.410887ms","start":"2026-02-14T04:58:00.559153Z","end":"2026-02-14T04:58:00.944564Z","steps":["trace[1647927940] 'process raft request' (duration: 384.597993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:00.94466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:00.559146Z","time spent":"385.488688ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:04.712677Z","caller":"traceutil/trace.go:171","msg":"trace[532479961] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"310.045824ms","start":"2026-02-14T04:58:04.402611Z","end":"2026-02-14T04:58:04.712656Z","steps":["trace[532479961] 'process raft request' (duration: 309.561186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:04.713751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:04.402469Z","time spent":"310.28179ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T04:58:08.802326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.872698ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T04:58:08.802391Z","caller":"traceutil/trace.go:171","msg":"trace[48976348] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"115.993706ms","start":"2026-02-14T04:58:08.686385Z","end":"2026-02-14T04:58:08.802379Z","steps":["trace[48976348] 'read index received' (duration: 27.48µs)","trace[48976348] 'applied index is now lower than readState.Index' (duration: 115.965456ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:08.802657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.282082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T04:58:08.802675Z","caller":"traceutil/trace.go:171","msg":"trace[1838098308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"116.340693ms","start":"2026-02-14T04:58:08.686327Z","end":"2026-02-14T04:58:08.802668Z","steps":["trace[1838098308] 'agreement among raft nodes before linearized reading' (duration: 116.302812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:18.407514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.727291ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T04:58:18.421191Z","caller":"traceutil/trace.go:171","msg":"trace[830066019] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"546.125553ms","start":"2026-02-14T04:58:17.875041Z","end":"2026-02-14T04:58:18.421167Z","steps":["trace[830066019] 'process raft request' (duration: 545.876928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:18.421331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T04:58:17.875024Z","time spent":"546.216284ms","remote":"127.0.0.1:45386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T04:58:19.209895Z","caller":"traceutil/trace.go:171","msg":"trace[1907971419] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"185.072487ms","start":"2026-02-14T04:58:19.0248Z","end":"2026-02-14T04:58:19.209872Z","steps":["trace[1907971419] 'read index received' (duration: 119.360389ms)","trace[1907971419] 'applied index is now lower than readState.Index' (duration: 65.711008ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:19.210174Z","caller":"traceutil/trace.go:171","msg":"trace[584788685] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"252.980334ms","start":"2026-02-14T04:58:18.957176Z","end":"2026-02-14T04:58:19.210156Z","steps":["trace[584788685] 'process raft request' (duration: 187.056612ms)","trace[584788685] 'compare' (duration: 65.535675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:19.210499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.681487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-14T04:58:19.210535Z","caller":"traceutil/trace.go:171","msg":"trace[134268887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"185.729428ms","start":"2026-02-14T04:58:19.024794Z","end":"2026-02-14T04:58:19.210524Z","steps":["trace[134268887] 'agreement among raft nodes before linearized reading' (duration: 185.569495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:19.210723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.751227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-14T04:58:19.210751Z","caller":"traceutil/trace.go:171","msg":"trace[241905083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"159.801949ms","start":"2026-02-14T04:58:19.050941Z","end":"2026-02-14T04:58:19.210743Z","steps":["trace[241905083] 'agreement among raft nodes before linearized reading' (duration: 159.724048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:19.211003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.575751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-14T04:58:19.211039Z","caller":"traceutil/trace.go:171","msg":"trace[1744389189] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1033; }","duration":"150.612633ms","start":"2026-02-14T04:58:19.060414Z","end":"2026-02-14T04:58:19.211027Z","steps":["trace[1744389189] 'agreement among raft nodes before linearized reading' (duration: 150.51318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T04:58:19.211959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.684114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-14T04:58:19.212658Z","caller":"traceutil/trace.go:171","msg":"trace[52839682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"156.395308ms","start":"2026-02-14T04:58:19.056249Z","end":"2026-02-14T04:58:19.212644Z","steps":["trace[52839682] 'agreement among raft nodes before linearized reading' (duration: 155.627374ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T04:58:49.763801Z","caller":"traceutil/trace.go:171","msg":"trace[1176682511] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1204; }","duration":"241.04814ms","start":"2026-02-14T04:58:49.522737Z","end":"2026-02-14T04:58:49.763785Z","steps":["trace[1176682511] 'read index received' (duration: 230.382622ms)","trace[1176682511] 'applied index is now lower than readState.Index' (duration: 10.664908ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T04:58:49.764868Z","caller":"traceutil/trace.go:171","msg":"trace[1414464125] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"242.7889ms","start":"2026-02-14T04:58:49.522028Z","end":"2026-02-14T04:58:49.764817Z","steps":["trace[1414464125] 'process raft request' (duration: 231.083125ms)","trace[1414464125] 'compare' (duration: 10.409633ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T04:58:49.770229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.476526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-14T04:58:49.770262Z","caller":"traceutil/trace.go:171","msg":"trace[294143144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"247.544797ms","start":"2026-02-14T04:58:49.522708Z","end":"2026-02-14T04:58:49.770253Z","steps":["trace[294143144] 'agreement among raft nodes before linearized reading' (duration: 247.411745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:00:34.710509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.727378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:00:34.710981Z","caller":"traceutil/trace.go:171","msg":"trace[522918616] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"170.098638ms","start":"2026-02-14T05:00:34.540832Z","end":"2026-02-14T05:00:34.71093Z","steps":["trace[522918616] 'compare' (duration: 159.586186ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:16.148879Z","caller":"traceutil/trace.go:171","msg":"trace[1127352214] linearizableReadLoop","detail":"{readStateIndex:1848; appliedIndex:1847; }","duration":"152.439912ms","start":"2026-02-14T05:01:15.996404Z","end":"2026-02-14T05:01:16.148844Z","steps":["trace[1127352214] 'read index received' (duration: 152.106677ms)","trace[1127352214] 'applied index is now lower than readState.Index' (duration: 331.835µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:16.14915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.708996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-14T05:01:16.149192Z","caller":"traceutil/trace.go:171","msg":"trace[1024762181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1772; }","duration":"152.837789ms","start":"2026-02-14T05:01:15.996341Z","end":"2026-02-14T05:01:16.149179Z","steps":["trace[1024762181] 'agreement among raft nodes before linearized reading' (duration: 152.635185ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:16.149158Z","caller":"traceutil/trace.go:171","msg":"trace[1396574039] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"172.852426ms","start":"2026-02-14T05:01:15.976274Z","end":"2026-02-14T05:01:16.149126Z","steps":["trace[1396574039] 'process raft request' (duration: 172.357368ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:21.821531Z","caller":"traceutil/trace.go:171","msg":"trace[1618895690] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"130.5619ms","start":"2026-02-14T05:01:21.690937Z","end":"2026-02-14T05:01:21.821499Z","steps":["trace[1618895690] 'process raft request' (duration: 81.465196ms)","trace[1618895690] 'compare' (duration: 48.446723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:22.397465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.665638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:01:22.398381Z","caller":"traceutil/trace.go:171","msg":"trace[1134458470] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"190.386381ms","start":"2026-02-14T05:01:22.207962Z","end":"2026-02-14T05:01:22.398348Z","steps":["trace[1134458470] 'process raft request' (duration: 64.715476ms)","trace[1134458470] 'compare' (duration: 124.2288ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:01:22.575494Z","caller":"traceutil/trace.go:171","msg":"trace[1635354006] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"123.230093ms","start":"2026-02-14T05:01:22.451992Z","end":"2026-02-14T05:01:22.575222Z","steps":["trace[1635354006] 'process raft request' (duration: 76.300977ms)","trace[1635354006] 'compare' (duration: 46.829014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:23.028515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.122415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T05:01:23.028725Z","caller":"traceutil/trace.go:171","msg":"trace[776770519] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1878; }","duration":"341.929777ms","start":"2026-02-14T05:01:22.686771Z","end":"2026-02-14T05:01:23.028701Z","steps":["trace[776770519] 'read index received' (duration: 151.189709ms)","trace[776770519] 'applied index is now lower than readState.Index' (duration: 190.738128ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:01:23.02904Z","caller":"traceutil/trace.go:171","msg":"trace[163204564] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"397.177178ms","start":"2026-02-14T05:01:22.631846Z","end":"2026-02-14T05:01:23.029023Z","steps":["trace[163204564] 'process raft request' (duration: 206.410101ms)","trace[163204564] 'compare' (duration: 189.960283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T05:01:23.029114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:22.631827Z","time spent":"397.247509ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T05:01:23.029346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.578418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T05:01:23.030205Z","caller":"traceutil/trace.go:171","msg":"trace[281049019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1802; }","duration":"343.455304ms","start":"2026-02-14T05:01:22.686734Z","end":"2026-02-14T05:01:23.030189Z","steps":["trace[281049019] 'agreement among raft nodes before linearized reading' (duration: 342.576718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:23.030265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T05:01:22.686704Z","time spent":"343.548986ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T05:01:23.030516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.180718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-14T05:01:23.030613Z","caller":"traceutil/trace.go:171","msg":"trace[1182035998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1802; }","duration":"255.368641ms","start":"2026-02-14T05:01:22.775221Z","end":"2026-02-14T05:01:23.030589Z","steps":["trace[1182035998] 'agreement among raft nodes before linearized reading' (duration: 255.006955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:23.031086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.259208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-14T05:01:23.031137Z","caller":"traceutil/trace.go:171","msg":"trace[1986294637] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1802; }","duration":"194.32789ms","start":"2026-02-14T05:01:22.836793Z","end":"2026-02-14T05:01:23.031121Z","steps":["trace[1986294637] 'agreement among raft nodes before linearized reading' (duration: 194.205808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:01:23.031487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.198444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-14T05:01:23.031696Z","caller":"traceutil/trace.go:171","msg":"trace[318703050] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:1802; }","duration":"249.426768ms","start":"2026-02-14T05:01:22.782256Z","end":"2026-02-14T05:01:23.031683Z","steps":["trace[318703050] 'agreement among raft nodes before linearized reading' (duration: 249.152242ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:01:25.952878Z","caller":"traceutil/trace.go:171","msg":"trace[1199291361] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"110.721606ms","start":"2026-02-14T05:01:25.842122Z","end":"2026-02-14T05:01:25.952844Z","steps":["trace[1199291361] 'process raft request' (duration: 110.428591ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:01.160908Z","caller":"traceutil/trace.go:171","msg":"trace[1265207704] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"101.598566ms","start":"2026-02-14T05:02:01.059216Z","end":"2026-02-14T05:02:01.160815Z","steps":["trace[1265207704] 'process raft request' (duration: 100.68816ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:03.445122Z","caller":"traceutil/trace.go:171","msg":"trace[1232004762] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2070; }","duration":"157.38749ms","start":"2026-02-14T05:02:03.287694Z","end":"2026-02-14T05:02:03.445081Z","steps":["trace[1232004762] 'read index received' (duration: 156.84633ms)","trace[1232004762] 'applied index is now lower than readState.Index' (duration: 534.16µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:03.445584Z","caller":"traceutil/trace.go:171","msg":"trace[1346167215] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"159.324975ms","start":"2026-02-14T05:02:03.286238Z","end":"2026-02-14T05:02:03.445563Z","steps":["trace[1346167215] 'process raft request' (duration: 158.490909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:03.446087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.981867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:661"} {"level":"info","ts":"2026-02-14T05:02:03.446338Z","caller":"traceutil/trace.go:171","msg":"trace[1155877603] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1984; }","duration":"105.265554ms","start":"2026-02-14T05:02:03.341043Z","end":"2026-02-14T05:02:03.446309Z","steps":["trace[1155877603] 'agreement among raft nodes before linearized reading' (duration: 104.935037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T05:02:03.446842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.136272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"info","ts":"2026-02-14T05:02:03.447012Z","caller":"traceutil/trace.go:171","msg":"trace[1657281405] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1984; }","duration":"159.317034ms","start":"2026-02-14T05:02:03.287681Z","end":"2026-02-14T05:02:03.446997Z","steps":["trace[1657281405] 'agreement among raft nodes before linearized reading' (duration: 159.106111ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T05:02:03.598598Z","caller":"traceutil/trace.go:171","msg":"trace[1320962525] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"137.921449ms","start":"2026-02-14T05:02:03.460648Z","end":"2026-02-14T05:02:03.598569Z","steps":["trace[1320962525] 'process raft request' (duration: 114.195253ms)","trace[1320962525] 'compare' (duration: 23.087605ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T05:02:03.598976Z","caller":"traceutil/trace.go:171","msg":"trace[117384356] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"136.895152ms","start":"2026-02-14T05:02:03.462063Z","end":"2026-02-14T05:02:03.598958Z","steps":["trace[117384356] 'process raft request' (duration: 136.056657ms)"],"step_count":1}