{"level":"warn","ts":"2026-02-07T04:03:09.024683Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T04:03:09.024794Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-07T04:03:09.024865Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T04:03:09.024875Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-07T04:03:09.024891Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-07T04:03:09.026649Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-02-07T04:03:09.028157Z","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.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-07T04:03:09.03914Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.635121ms"} {"level":"info","ts":"2026-02-07T04:03:09.064563Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-02-07T04:03:09.064721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T04:03:09.064763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-02-07T04:03:09.064798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T04:03:09.064817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-02-07T04:03:09.064854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-02-07T04:03:09.072248Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T04:03:09.078446Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T04:03:09.079788Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-07T04:03:09.081471Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T04:03:09.082078Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T04:03:09.082278Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T04:03:09.082344Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T04:03:09.083252Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T04:03:09.082944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-02-07T04:03:09.083858Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-07T04:03:09.099379Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-07T04:03:09.09967Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-07T04:03:09.099726Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-07T04:03:09.10127Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T04:03:09.101297Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T04:03:10.066045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T04:03:10.066127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T04:03:10.066155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-02-07T04:03:10.066177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-02-07T04:03:10.066192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-07T04:03:10.066217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-02-07T04:03:10.06624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-07T04:03:10.068992Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T04:03:10.069101Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:03:10.069168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T04:03:10.069389Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:03:10.073857Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T04:03:10.073879Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T04:03:10.074317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T04:03:10.074501Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:03:10.076114Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:03:10.076197Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T04:03:10.077373Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"warn","ts":"2026-02-07T04:03:31.623534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:03:32.124583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:03:32.536631Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.86426585s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T04:03:32.537034Z","caller":"traceutil/trace.go:171","msg":"trace[795907966] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.864765115s","start":"2026-02-07T04:03:30.672258Z","end":"2026-02-07T04:03:32.537024Z","steps":["trace[795907966] 'process raft request' (duration: 1.864504687s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:32.638983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:30.672242Z","time spent":"1.966271083s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:03:32.536879Z","caller":"traceutil/trace.go:171","msg":"trace[1630784031] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"1.480004424s","start":"2026-02-07T04:03:31.056856Z","end":"2026-02-07T04:03:32.536861Z","steps":["trace[1630784031] 'read index received' (duration: 1.479900681s)","trace[1630784031] 'applied index is now lower than readState.Index' (duration: 103.103µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:33.557847Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746091,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:03:34.062153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746091,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:03:34.177242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.108810789s","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-07T04:03:34.177345Z","caller":"traceutil/trace.go:171","msg":"trace[1875197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"2.108957094s","start":"2026-02-07T04:03:32.068366Z","end":"2026-02-07T04:03:34.177324Z","steps":["trace[1875197] 'agreement among raft nodes before linearized reading' (duration: 572.139274ms)","trace[1875197] 'range keys from in-memory index tree' (duration: 1.536671325s)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:34.177388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:32.068348Z","time spent":"2.109028146s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:03:34.177583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.012537633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T04:03:34.177614Z","caller":"traceutil/trace.go:171","msg":"trace[697262183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"2.012591605s","start":"2026-02-07T04:03:32.165011Z","end":"2026-02-07T04:03:34.177602Z","steps":["trace[697262183] 'agreement among raft nodes before linearized reading' (duration: 475.512197ms)","trace[697262183] 'range keys from in-memory index tree' (duration: 1.537032567s)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:34.177643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:32.164997Z","time spent":"2.012638337s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:03:34.177754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.108226761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:03:34.177782Z","caller":"traceutil/trace.go:171","msg":"trace[333611672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"2.108290092s","start":"2026-02-07T04:03:32.069482Z","end":"2026-02-07T04:03:34.177772Z","steps":["trace[333611672] 'agreement among raft nodes before linearized reading' (duration: 571.045949ms)","trace[333611672] 'range keys from in-memory index tree' (duration: 1.537203672s)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:34.177807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:32.06946Z","time spent":"2.108341404s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:03:34.177903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.121051549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:03:34.177927Z","caller":"traceutil/trace.go:171","msg":"trace[1758863664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"3.121105291s","start":"2026-02-07T04:03:31.056813Z","end":"2026-02-07T04:03:34.177918Z","steps":["trace[1758863664] 'agreement among raft nodes before linearized reading' (duration: 1.583763124s)","trace[1758863664] 'range keys from in-memory index tree' (duration: 1.537314646s)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:34.178412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:31.056795Z","time spent":"3.121603467s","remote":"127.0.0.1:36202","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-07T04:03:34.43461Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.735377423s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T04:03:34.435013Z","caller":"traceutil/trace.go:171","msg":"trace[375173752] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.735868019s","start":"2026-02-07T04:03:32.699124Z","end":"2026-02-07T04:03:34.434992Z","steps":["trace[375173752] 'process raft request' (duration: 1.735741704s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:34.435184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:32.699105Z","time spent":"1.736006143s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:03:34.435246Z","caller":"traceutil/trace.go:171","msg":"trace[530348958] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:430; }","duration":"1.377853666s","start":"2026-02-07T04:03:33.057376Z","end":"2026-02-07T04:03:34.43523Z","steps":["trace[530348958] 'read index received' (duration: 1.377847986s)","trace[530348958] 'applied index is now lower than readState.Index' (duration: 4.86µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:34.435359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37796917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:03:34.435377Z","caller":"traceutil/trace.go:171","msg":"trace[1421211673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"1.378040212s","start":"2026-02-07T04:03:33.05733Z","end":"2026-02-07T04:03:34.43537Z","steps":["trace[1421211673] 'agreement among raft nodes before linearized reading' (duration: 1.377966679s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:34.435396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:33.057312Z","time spent":"1.378080193s","remote":"127.0.0.1:36206","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-07T04:03:34.435646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.745302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-07T04:03:34.435661Z","caller":"traceutil/trace.go:171","msg":"trace[1959752281] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:419; }","duration":"134.788744ms","start":"2026-02-07T04:03:34.300868Z","end":"2026-02-07T04:03:34.435657Z","steps":["trace[1959752281] 'agreement among raft nodes before linearized reading' (duration: 134.751603ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:03:34.435666Z","caller":"traceutil/trace.go:171","msg":"trace[1197921065] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"246.90916ms","start":"2026-02-07T04:03:34.188741Z","end":"2026-02-07T04:03:34.43565Z","steps":["trace[1197921065] 'process raft request' (duration: 246.789496ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:03:34.435738Z","caller":"traceutil/trace.go:171","msg":"trace[1791093974] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"245.910807ms","start":"2026-02-07T04:03:34.189822Z","end":"2026-02-07T04:03:34.435733Z","steps":["trace[1791093974] 'process raft request' (duration: 245.764553ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:03:34.435784Z","caller":"traceutil/trace.go:171","msg":"trace[1296168504] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"245.871516ms","start":"2026-02-07T04:03:34.189907Z","end":"2026-02-07T04:03:34.435779Z","steps":["trace[1296168504] 'process raft request' (duration: 245.6961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:39.87651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.989984ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T04:03:44.566968Z","caller":"traceutil/trace.go:171","msg":"trace[1916358047] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"102.972441ms","start":"2026-02-07T04:03:44.463974Z","end":"2026-02-07T04:03:44.566947Z","steps":["trace[1916358047] 'process raft request' (duration: 102.829626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:45.267427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.971628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:03:45.267497Z","caller":"traceutil/trace.go:171","msg":"trace[494249645] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"695.856716ms","start":"2026-02-07T04:03:44.571628Z","end":"2026-02-07T04:03:45.267485Z","steps":["trace[494249645] 'process raft request' (duration: 385.774014ms)","trace[494249645] 'compare' (duration: 309.899146ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:45.267529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:44.571609Z","time spent":"695.907647ms","remote":"127.0.0.1:36462","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-07T04:03:45.478383Z","caller":"traceutil/trace.go:171","msg":"trace[105952616] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:493; }","duration":"795.699025ms","start":"2026-02-07T04:03:44.682664Z","end":"2026-02-07T04:03:45.478363Z","steps":["trace[105952616] 'read index received' (duration: 274.747044ms)","trace[105952616] 'applied index is now lower than readState.Index' (duration: 520.951151ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:45.478672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.989626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:03:45.478727Z","caller":"traceutil/trace.go:171","msg":"trace[1110432883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"796.079178ms","start":"2026-02-07T04:03:44.682633Z","end":"2026-02-07T04:03:45.478712Z","steps":["trace[1110432883] 'agreement among raft nodes before linearized reading' (duration: 795.889402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:45.478754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:44.682619Z","time spent":"796.12868ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:03:45.478754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.943296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:03:45.478795Z","caller":"traceutil/trace.go:171","msg":"trace[804449765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"129.027799ms","start":"2026-02-07T04:03:45.349755Z","end":"2026-02-07T04:03:45.478783Z","steps":["trace[804449765] 'agreement among raft nodes before linearized reading' (duration: 128.959417ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:03:45.478958Z","caller":"traceutil/trace.go:171","msg":"trace[167491568] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"906.299582ms","start":"2026-02-07T04:03:44.572651Z","end":"2026-02-07T04:03:45.47895Z","steps":["trace[167491568] 'process raft request' (duration: 905.540888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:45.479011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:44.572643Z","time spent":"906.329643ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:03:45.479088Z","caller":"traceutil/trace.go:171","msg":"trace[679835017] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"906.393536ms","start":"2026-02-07T04:03:44.572688Z","end":"2026-02-07T04:03:45.479081Z","steps":["trace[679835017] 'process raft request' (duration: 905.628011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:45.479114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:44.572684Z","time spent":"906.414266ms","remote":"127.0.0.1:36462","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-07T04:03:47.194078Z","caller":"traceutil/trace.go:171","msg":"trace[129004802] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"155.753838ms","start":"2026-02-07T04:03:47.038302Z","end":"2026-02-07T04:03:47.194056Z","steps":["trace[129004802] 'process raft request' (duration: 155.570622ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:03:47.37864Z","caller":"traceutil/trace.go:171","msg":"trace[410720923] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"105.325047ms","start":"2026-02-07T04:03:47.273302Z","end":"2026-02-07T04:03:47.378627Z","steps":["trace[410720923] 'process raft request' (duration: 104.362156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:47.988581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.82255ms","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-07T04:03:47.988642Z","caller":"traceutil/trace.go:171","msg":"trace[283659625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"503.914812ms","start":"2026-02-07T04:03:47.484714Z","end":"2026-02-07T04:03:47.988629Z","steps":["trace[283659625] 'range keys from in-memory index tree' (duration: 503.714246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:47.988645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.548824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T04:03:47.988668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:47.484701Z","time spent":"503.961174ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-07T04:03:47.988707Z","caller":"traceutil/trace.go:171","msg":"trace[223079017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"193.648177ms","start":"2026-02-07T04:03:47.795043Z","end":"2026-02-07T04:03:47.988692Z","steps":["trace[223079017] 'range keys from in-memory index tree' (duration: 193.392939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:47.988845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.789836ms","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-07T04:03:47.988865Z","caller":"traceutil/trace.go:171","msg":"trace[1171198426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"502.817677ms","start":"2026-02-07T04:03:47.486041Z","end":"2026-02-07T04:03:47.988859Z","steps":["trace[1171198426] 'range keys from in-memory index tree' (duration: 502.719433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:47.988881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:47.486037Z","time spent":"502.841128ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:03:47.988993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.246532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:03:47.989033Z","caller":"traceutil/trace.go:171","msg":"trace[1544077149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"499.294024ms","start":"2026-02-07T04:03:47.489733Z","end":"2026-02-07T04:03:47.989027Z","steps":["trace[1544077149] 'range keys from in-memory index tree' (duration: 499.198051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:47.989049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:47.489729Z","time spent":"499.316404ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:03:52.538931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746270,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:03:52.610234Z","caller":"traceutil/trace.go:171","msg":"trace[748724993] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"572.169054ms","start":"2026-02-07T04:03:52.038045Z","end":"2026-02-07T04:03:52.610214Z","steps":["trace[748724993] 'read index received' (duration: 571.995079ms)","trace[748724993] 'applied index is now lower than readState.Index' (duration: 173.005µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:03:52.610353Z","caller":"traceutil/trace.go:171","msg":"trace[2061628042] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"573.441329ms","start":"2026-02-07T04:03:52.036902Z","end":"2026-02-07T04:03:52.610344Z","steps":["trace[2061628042] 'process raft request' (duration: 573.153591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:52.61045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:52.036885Z","time spent":"573.493821ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:03:52.610651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.610577ms","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-07T04:03:52.61068Z","caller":"traceutil/trace.go:171","msg":"trace[561971386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:494; }","duration":"572.696269ms","start":"2026-02-07T04:03:52.037974Z","end":"2026-02-07T04:03:52.61067Z","steps":["trace[561971386] 'agreement among raft nodes before linearized reading' (duration: 572.587676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:52.610709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:52.037963Z","time spent":"572.74076ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:03:52.611037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.77711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-07T04:03:52.611128Z","caller":"traceutil/trace.go:171","msg":"trace[466345815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"569.891063ms","start":"2026-02-07T04:03:52.041198Z","end":"2026-02-07T04:03:52.611089Z","steps":["trace[466345815] 'agreement among raft nodes before linearized reading' (duration: 569.631746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:52.611213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:52.04119Z","time spent":"569.969525ms","remote":"127.0.0.1:36462","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-07T04:03:53.25261Z","caller":"traceutil/trace.go:171","msg":"trace[1895207602] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"635.328733ms","start":"2026-02-07T04:03:52.617251Z","end":"2026-02-07T04:03:53.252579Z","steps":["trace[1895207602] 'process raft request' (duration: 605.532747ms)","trace[1895207602] 'compare' (duration: 29.56762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:53.252745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:52.617228Z","time spent":"635.444517ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:03:53.883586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.325249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:03:53.883673Z","caller":"traceutil/trace.go:171","msg":"trace[1624829573] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:512; }","duration":"1.14181082s","start":"2026-02-07T04:03:52.741846Z","end":"2026-02-07T04:03:53.883656Z","steps":["trace[1624829573] 'read index received' (duration: 480.887214ms)","trace[1624829573] 'applied index is now lower than readState.Index' (duration: 660.922446ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:03:53.883901Z","caller":"traceutil/trace.go:171","msg":"trace[1511657055] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.266465904s","start":"2026-02-07T04:03:52.617422Z","end":"2026-02-07T04:03:53.883888Z","steps":["trace[1511657055] 'process raft request' (duration: 1.163762255s)","trace[1511657055] 'compare' (duration: 101.876307ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:03:53.883962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:52.617409Z","time spent":"1.266521335s","remote":"127.0.0.1:36462","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-07T04:03:53.884207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142359936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zh6dx\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-07T04:03:53.884235Z","caller":"traceutil/trace.go:171","msg":"trace[1132643159] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zh6dx; range_end:; response_count:1; response_revision:496; }","duration":"1.142413626s","start":"2026-02-07T04:03:52.741812Z","end":"2026-02-07T04:03:53.884226Z","steps":["trace[1132643159] 'agreement among raft nodes before linearized reading' (duration: 1.142307734s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:53.884263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:52.741784Z","time spent":"1.142471738s","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-zh6dx\" "} {"level":"warn","ts":"2026-02-07T04:03:53.88444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.28789ms","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-07T04:03:53.884467Z","caller":"traceutil/trace.go:171","msg":"trace[1814161266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"486.342152ms","start":"2026-02-07T04:03:53.398117Z","end":"2026-02-07T04:03:53.884459Z","steps":["trace[1814161266] 'agreement among raft nodes before linearized reading' (duration: 486.26953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:03:53.884491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:03:53.398102Z","time spent":"486.383502ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:04:05.884873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.760655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:04:05.884934Z","caller":"traceutil/trace.go:171","msg":"trace[1258445819] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"1.002011386s","start":"2026-02-07T04:04:04.882913Z","end":"2026-02-07T04:04:05.884925Z","steps":["trace[1258445819] 'read index received' (duration: 6.28251ms)","trace[1258445819] 'applied index is now lower than readState.Index' (duration: 995.728276ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:05.88501Z","caller":"traceutil/trace.go:171","msg":"trace[2121065595] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.012925252s","start":"2026-02-07T04:04:04.872079Z","end":"2026-02-07T04:04:05.885004Z","steps":["trace[2121065595] 'process raft request' (duration: 199.994413ms)","trace[2121065595] 'compare' (duration: 812.690122ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:05.885035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:04.872063Z","time spent":"1.012961722s","remote":"127.0.0.1:47776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:04:05.885192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002275844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zh6dx\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-07T04:04:05.885207Z","caller":"traceutil/trace.go:171","msg":"trace[1831385929] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zh6dx; range_end:; response_count:1; response_revision:618; }","duration":"1.002315964s","start":"2026-02-07T04:04:04.882886Z","end":"2026-02-07T04:04:05.885202Z","steps":["trace[1831385929] 'agreement among raft nodes before linearized reading' (duration: 1.002229202s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:05.885219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:04.882875Z","time spent":"1.002341285s","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-zh6dx\" "} {"level":"warn","ts":"2026-02-07T04:04:06.558357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.237656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:04:06.558481Z","caller":"traceutil/trace.go:171","msg":"trace[671814771] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"673.525638ms","start":"2026-02-07T04:04:05.884946Z","end":"2026-02-07T04:04:06.558471Z","steps":["trace[671814771] 'read index received' (duration: 288.123868ms)","trace[671814771] 'applied index is now lower than readState.Index' (duration: 385.40074ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:06.558612Z","caller":"traceutil/trace.go:171","msg":"trace[1333748093] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.350668263s","start":"2026-02-07T04:04:05.207937Z","end":"2026-02-07T04:04:06.558605Z","steps":["trace[1333748093] 'process raft request' (duration: 965.125698ms)","trace[1333748093] 'compare' (duration: 385.141764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:06.573327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:05.207917Z","time spent":"1.365335219s","remote":"127.0.0.1:47776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T04:04:06.57355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268470878s","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-07T04:04:06.573575Z","caller":"traceutil/trace.go:171","msg":"trace[583122426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.268499588s","start":"2026-02-07T04:04:05.305067Z","end":"2026-02-07T04:04:06.573567Z","steps":["trace[583122426] 'agreement among raft nodes before linearized reading' (duration: 1.268342454s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:06.573592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:05.305033Z","time spent":"1.26855572s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:04:06.573733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578236631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:04:06.573786Z","caller":"traceutil/trace.go:171","msg":"trace[1488129118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.578310333s","start":"2026-02-07T04:04:04.99547Z","end":"2026-02-07T04:04:06.57378Z","steps":["trace[1488129118] 'agreement among raft nodes before linearized reading' (duration: 1.578231031s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:06.573818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:04.995457Z","time spent":"1.578340834s","remote":"127.0.0.1:36462","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":"warn","ts":"2026-02-07T04:04:06.911919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.002676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:04:06.912012Z","caller":"traceutil/trace.go:171","msg":"trace[2097390680] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"353.490336ms","start":"2026-02-07T04:04:06.558506Z","end":"2026-02-07T04:04:06.911996Z","steps":["trace[2097390680] 'read index received' (duration: 144.345456ms)","trace[2097390680] 'applied index is now lower than readState.Index' (duration: 209.14372ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:06.912289Z","caller":"traceutil/trace.go:171","msg":"trace[492823915] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"944.597644ms","start":"2026-02-07T04:04:05.967677Z","end":"2026-02-07T04:04:06.912274Z","steps":["trace[492823915] 'process raft request' (duration: 735.168936ms)","trace[492823915] 'compare' (duration: 208.684938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:06.912356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:05.967659Z","time spent":"944.665185ms","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:04:06.912588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.890216ms","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-07T04:04:06.912623Z","caller":"traceutil/trace.go:171","msg":"trace[918952275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"973.972358ms","start":"2026-02-07T04:04:05.938642Z","end":"2026-02-07T04:04:06.912614Z","steps":["trace[918952275] 'agreement among raft nodes before linearized reading' (duration: 973.868165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:06.912655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:05.938629Z","time spent":"974.02078ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:04:06.912823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.764427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:04:06.912856Z","caller":"traceutil/trace.go:171","msg":"trace[923916425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"985.797338ms","start":"2026-02-07T04:04:05.927051Z","end":"2026-02-07T04:04:06.912848Z","steps":["trace[923916425] 'agreement among raft nodes before linearized reading' (duration: 985.717096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:06.912886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:05.927021Z","time spent":"985.85951ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-07T04:04:07.152843Z","caller":"traceutil/trace.go:171","msg":"trace[861525729] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"231.406993ms","start":"2026-02-07T04:04:06.92142Z","end":"2026-02-07T04:04:07.152827Z","steps":["trace[861525729] 'process raft request' (duration: 231.379452ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:07.152947Z","caller":"traceutil/trace.go:171","msg":"trace[227210133] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"566.773099ms","start":"2026-02-07T04:04:06.586156Z","end":"2026-02-07T04:04:07.152929Z","steps":["trace[227210133] 'process raft request' (duration: 566.583854ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:07.153Z","caller":"traceutil/trace.go:171","msg":"trace[1541309943] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"240.93087ms","start":"2026-02-07T04:04:06.912062Z","end":"2026-02-07T04:04:07.152993Z","steps":["trace[1541309943] 'read index received' (duration: 142.328012ms)","trace[1541309943] 'applied index is now lower than readState.Index' (duration: 98.601908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:07.15304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:06.586147Z","time spent":"566.84209ms","remote":"127.0.0.1:36462","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-07T04:04:07.153048Z","caller":"traceutil/trace.go:171","msg":"trace[620618138] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"231.736262ms","start":"2026-02-07T04:04:06.921305Z","end":"2026-02-07T04:04:07.153041Z","steps":["trace[620618138] 'process raft request' (duration: 231.467324ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:07.153176Z","caller":"traceutil/trace.go:171","msg":"trace[1625998825] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"573.374347ms","start":"2026-02-07T04:04:06.579795Z","end":"2026-02-07T04:04:07.15317Z","steps":["trace[1625998825] 'process raft request' (duration: 474.587774ms)","trace[1625998825] 'compare' (duration: 98.265489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:07.153206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:06.579782Z","time spent":"573.407788ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:04:07.153205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.745058ms","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-07T04:04:07.153227Z","caller":"traceutil/trace.go:171","msg":"trace[148852789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"314.79638ms","start":"2026-02-07T04:04:06.838424Z","end":"2026-02-07T04:04:07.153221Z","steps":["trace[148852789] 'agreement among raft nodes before linearized reading' (duration: 314.704577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:07.153248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:06.838411Z","time spent":"314.83185ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:04:07.153338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.923035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:07.153354Z","caller":"traceutil/trace.go:171","msg":"trace[2040848230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"344.963446ms","start":"2026-02-07T04:04:06.808385Z","end":"2026-02-07T04:04:07.153348Z","steps":["trace[2040848230] 'agreement among raft nodes before linearized reading' (duration: 344.939145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:07.153369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:06.808371Z","time spent":"344.993746ms","remote":"127.0.0.1:36202","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-07T04:04:07.153469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.971318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zh6dx\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-07T04:04:07.153489Z","caller":"traceutil/trace.go:171","msg":"trace[986284086] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zh6dx; range_end:; response_count:1; response_revision:624; }","duration":"232.007089ms","start":"2026-02-07T04:04:06.921477Z","end":"2026-02-07T04:04:07.153484Z","steps":["trace[986284086] 'agreement among raft nodes before linearized reading' (duration: 231.923526ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:08.423976Z","caller":"traceutil/trace.go:171","msg":"trace[845122570] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"263.042999ms","start":"2026-02-07T04:04:08.160911Z","end":"2026-02-07T04:04:08.423954Z","steps":["trace[845122570] 'read index received' (duration: 261.182129ms)","trace[845122570] 'applied index is now lower than readState.Index' (duration: 1.85976ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:08.424229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.283065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3769"} {"level":"info","ts":"2026-02-07T04:04:08.424245Z","caller":"traceutil/trace.go:171","msg":"trace[1929671901] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"264.441126ms","start":"2026-02-07T04:04:08.159779Z","end":"2026-02-07T04:04:08.42422Z","steps":["trace[1929671901] 'process raft request' (duration: 262.378351ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:08.424268Z","caller":"traceutil/trace.go:171","msg":"trace[1445504359] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:662; }","duration":"263.362527ms","start":"2026-02-07T04:04:08.160893Z","end":"2026-02-07T04:04:08.424256Z","steps":["trace[1445504359] 'agreement among raft nodes before linearized reading' (duration: 263.153762ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:09.62245Z","caller":"traceutil/trace.go:171","msg":"trace[538972909] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"455.328182ms","start":"2026-02-07T04:04:09.167099Z","end":"2026-02-07T04:04:09.622427Z","steps":["trace[538972909] 'process raft request' (duration: 455.192559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:09.622581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:09.167081Z","time spent":"455.435136ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:09.926523Z","caller":"traceutil/trace.go:171","msg":"trace[1060472618] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"759.264918ms","start":"2026-02-07T04:04:09.167243Z","end":"2026-02-07T04:04:09.926508Z","steps":["trace[1060472618] 'process raft request' (duration: 758.836596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:09.926932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:09.167238Z","time spent":"759.639678ms","remote":"127.0.0.1:36462","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-07T04:04:09.926611Z","caller":"traceutil/trace.go:171","msg":"trace[1332959268] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"759.306469ms","start":"2026-02-07T04:04:09.167297Z","end":"2026-02-07T04:04:09.926603Z","steps":["trace[1332959268] 'read index received' (duration: 455.652692ms)","trace[1332959268] 'applied index is now lower than readState.Index' (duration: 303.653127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:09.926746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.421072ms","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-07T04:04:09.927125Z","caller":"traceutil/trace.go:171","msg":"trace[1627708881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"759.840414ms","start":"2026-02-07T04:04:09.167263Z","end":"2026-02-07T04:04:09.927104Z","steps":["trace[1627708881] 'agreement among raft nodes before linearized reading' (duration: 759.361431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:09.927157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:09.167259Z","time spent":"759.891565ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T04:04:09.92677Z","caller":"traceutil/trace.go:171","msg":"trace[265273547] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"759.431753ms","start":"2026-02-07T04:04:09.167332Z","end":"2026-02-07T04:04:09.926764Z","steps":["trace[265273547] 'process raft request' (duration: 758.902088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:09.927422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:09.167328Z","time spent":"760.058609ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:09.926857Z","caller":"traceutil/trace.go:171","msg":"trace[2121419584] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"759.433543ms","start":"2026-02-07T04:04:09.167418Z","end":"2026-02-07T04:04:09.926851Z","steps":["trace[2121419584] 'process raft request' (duration: 758.843887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:09.927549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:09.167414Z","time spent":"760.100021ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:04:10.074718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.053878ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T04:04:10.07486Z","caller":"traceutil/trace.go:171","msg":"trace[1202903781] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"198.583034ms","start":"2026-02-07T04:04:09.87626Z","end":"2026-02-07T04:04:10.074843Z","steps":["trace[1202903781] 'process raft request' (duration: 198.511202ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:10.074882Z","caller":"traceutil/trace.go:171","msg":"trace[671539343] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"142.99863ms","start":"2026-02-07T04:04:09.931875Z","end":"2026-02-07T04:04:10.074874Z","steps":["trace[671539343] 'process raft request' (duration: 142.965589ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:10.075026Z","caller":"traceutil/trace.go:171","msg":"trace[292539960] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:688; }","duration":"147.948654ms","start":"2026-02-07T04:04:09.927073Z","end":"2026-02-07T04:04:10.075021Z","steps":["trace[292539960] 'read index received' (duration: 13.558877ms)","trace[292539960] 'applied index is now lower than readState.Index' (duration: 134.389317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:10.075099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.443718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:10.075112Z","caller":"traceutil/trace.go:171","msg":"trace[1322211810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"277.51854ms","start":"2026-02-07T04:04:09.797589Z","end":"2026-02-07T04:04:10.075107Z","steps":["trace[1322211810] 'agreement among raft nodes before linearized reading' (duration: 277.449568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:10.075217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.099011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-bqpjs\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-07T04:04:10.07523Z","caller":"traceutil/trace.go:171","msg":"trace[2116399359] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-bqpjs; range_end:; response_count:1; response_revision:668; }","duration":"198.136412ms","start":"2026-02-07T04:04:09.877089Z","end":"2026-02-07T04:04:10.075226Z","steps":["trace[2116399359] 'agreement among raft nodes before linearized reading' (duration: 198.084231ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:15.745031Z","caller":"traceutil/trace.go:171","msg":"trace[415051182] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"100.125545ms","start":"2026-02-07T04:04:15.644888Z","end":"2026-02-07T04:04:15.745013Z","steps":["trace[415051182] 'process raft request' (duration: 99.987061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:16.713227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.452303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:04:16.71328Z","caller":"traceutil/trace.go:171","msg":"trace[2077393084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"661.549464ms","start":"2026-02-07T04:04:16.051717Z","end":"2026-02-07T04:04:16.713267Z","steps":["trace[2077393084] 'range keys from in-memory index tree' (duration: 661.308678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:16.713315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.05169Z","time spent":"661.618447ms","remote":"127.0.0.1:36462","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":"warn","ts":"2026-02-07T04:04:16.713426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.281798ms","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-07T04:04:16.713477Z","caller":"traceutil/trace.go:171","msg":"trace[2029013245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"619.35687ms","start":"2026-02-07T04:04:16.094106Z","end":"2026-02-07T04:04:16.713463Z","steps":["trace[2029013245] 'range keys from in-memory index tree' (duration: 619.174265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:16.713505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.094101Z","time spent":"619.398451ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:04:16.713553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.149994ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:16.713594Z","caller":"traceutil/trace.go:171","msg":"trace[899542461] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:687; }","duration":"749.197375ms","start":"2026-02-07T04:04:15.964391Z","end":"2026-02-07T04:04:16.713589Z","steps":["trace[899542461] 'range keys from in-memory index tree' (duration: 749.144204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:16.713714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.426986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:04:16.713727Z","caller":"traceutil/trace.go:171","msg":"trace[1987435372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"697.464166ms","start":"2026-02-07T04:04:16.016259Z","end":"2026-02-07T04:04:16.713723Z","steps":["trace[1987435372] 'range keys from in-memory index tree' (duration: 697.353603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:16.71374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.016245Z","time spent":"697.492107ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:04:16.713861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.88816ms","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-07T04:04:16.713875Z","caller":"traceutil/trace.go:171","msg":"trace[1777545681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"667.913701ms","start":"2026-02-07T04:04:16.045957Z","end":"2026-02-07T04:04:16.71387Z","steps":["trace[1777545681] 'range keys from in-memory index tree' (duration: 667.793467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:16.713888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.04595Z","time spent":"667.934071ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:04:17.296647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746650,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:04:17.430528Z","caller":"traceutil/trace.go:171","msg":"trace[1947088017] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"712.636536ms","start":"2026-02-07T04:04:16.717878Z","end":"2026-02-07T04:04:17.430514Z","steps":["trace[1947088017] 'process raft request' (duration: 712.551624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:17.430623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.717865Z","time spent":"712.706628ms","remote":"127.0.0.1:36462","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-07T04:04:17.501244Z","caller":"traceutil/trace.go:171","msg":"trace[558317409] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:711; }","duration":"705.309745ms","start":"2026-02-07T04:04:16.795914Z","end":"2026-02-07T04:04:17.501224Z","steps":["trace[558317409] 'read index received' (duration: 634.583052ms)","trace[558317409] 'applied index is now lower than readState.Index' (duration: 70.725543ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:17.501364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.486619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:17.501394Z","caller":"traceutil/trace.go:171","msg":"trace[633009923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"705.557802ms","start":"2026-02-07T04:04:16.795828Z","end":"2026-02-07T04:04:17.501385Z","steps":["trace[633009923] 'agreement among raft nodes before linearized reading' (duration: 705.472199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:17.501424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.795815Z","time spent":"705.602273ms","remote":"127.0.0.1:36202","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-07T04:04:17.501772Z","caller":"traceutil/trace.go:171","msg":"trace[1572295120] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"783.146134ms","start":"2026-02-07T04:04:16.718615Z","end":"2026-02-07T04:04:17.501761Z","steps":["trace[1572295120] 'process raft request' (duration: 782.446275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:17.501851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.718608Z","time spent":"783.191485ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:17.501981Z","caller":"traceutil/trace.go:171","msg":"trace[802573986] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"782.321861ms","start":"2026-02-07T04:04:16.719649Z","end":"2026-02-07T04:04:17.501971Z","steps":["trace[802573986] 'process raft request' (duration: 781.503428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:17.502063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.719642Z","time spent":"782.393033ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:17.502203Z","caller":"traceutil/trace.go:171","msg":"trace[1083676119] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"782.313191ms","start":"2026-02-07T04:04:16.71988Z","end":"2026-02-07T04:04:17.502193Z","steps":["trace[1083676119] 'process raft request' (duration: 781.311133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:17.502269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:16.719875Z","time spent":"782.365732ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:17.964333Z","caller":"traceutil/trace.go:171","msg":"trace[275174865] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"211.418287ms","start":"2026-02-07T04:04:17.752894Z","end":"2026-02-07T04:04:17.964312Z","steps":["trace[275174865] 'process raft request' (duration: 198.718166ms)","trace[275174865] 'compare' (duration: 12.565447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:18.354274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.74775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:04:18.354388Z","caller":"traceutil/trace.go:171","msg":"trace[86633514] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"218.977626ms","start":"2026-02-07T04:04:18.135386Z","end":"2026-02-07T04:04:18.354364Z","steps":["trace[86633514] 'read index received' (duration: 109.002179ms)","trace[86633514] 'applied index is now lower than readState.Index' (duration: 109.974377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:18.354468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.076538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:04:18.354502Z","caller":"traceutil/trace.go:171","msg":"trace[1325508971] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:694; }","duration":"219.14203ms","start":"2026-02-07T04:04:18.135351Z","end":"2026-02-07T04:04:18.354493Z","steps":["trace[1325508971] 'agreement among raft nodes before linearized reading' (duration: 219.065098ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:18.354929Z","caller":"traceutil/trace.go:171","msg":"trace[83165032] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"294.511902ms","start":"2026-02-07T04:04:18.0604Z","end":"2026-02-07T04:04:18.354912Z","steps":["trace[83165032] 'process raft request' (duration: 184.027021ms)","trace[83165032] 'compare' (duration: 109.650168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:19.016074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.28647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:19.016199Z","caller":"traceutil/trace.go:171","msg":"trace[1587132171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"221.459584ms","start":"2026-02-07T04:04:18.794721Z","end":"2026-02-07T04:04:19.01618Z","steps":["trace[1587132171] 'range keys from in-memory index tree' (duration: 221.188397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:32.053156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746788,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:04:32.49241Z","caller":"traceutil/trace.go:171","msg":"trace[1158496906] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"939.750618ms","start":"2026-02-07T04:04:31.552642Z","end":"2026-02-07T04:04:32.492393Z","steps":["trace[1158496906] 'read index received' (duration: 939.533522ms)","trace[1158496906] 'applied index is now lower than readState.Index' (duration: 216.386µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:32.492655Z","caller":"traceutil/trace.go:171","msg":"trace[1531972956] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"982.783346ms","start":"2026-02-07T04:04:31.509864Z","end":"2026-02-07T04:04:32.492648Z","steps":["trace[1531972956] 'process raft request' (duration: 982.372484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:32.492733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:31.509852Z","time spent":"982.824966ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:04:32.492888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.249091ms","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-07T04:04:32.492909Z","caller":"traceutil/trace.go:171","msg":"trace[1713443838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"940.290303ms","start":"2026-02-07T04:04:31.552611Z","end":"2026-02-07T04:04:32.492901Z","steps":["trace[1713443838] 'agreement among raft nodes before linearized reading' (duration: 940.220511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:32.492927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:31.552599Z","time spent":"940.324603ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:04:32.493045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.640025ms","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-07T04:04:32.49307Z","caller":"traceutil/trace.go:171","msg":"trace[434877850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"918.683376ms","start":"2026-02-07T04:04:31.574374Z","end":"2026-02-07T04:04:32.493057Z","steps":["trace[434877850] 'agreement among raft nodes before linearized reading' (duration: 918.628784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:32.493086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:31.574361Z","time spent":"918.721867ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:04:32.493223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.481106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:04:32.493241Z","caller":"traceutil/trace.go:171","msg":"trace[121287855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"392.529408ms","start":"2026-02-07T04:04:32.100707Z","end":"2026-02-07T04:04:32.493236Z","steps":["trace[121287855] 'agreement among raft nodes before linearized reading' (duration: 392.482307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:32.493261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:32.100693Z","time spent":"392.565269ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:04:32.493352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.085985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:32.493372Z","caller":"traceutil/trace.go:171","msg":"trace[165358976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"698.143057ms","start":"2026-02-07T04:04:31.795222Z","end":"2026-02-07T04:04:32.493365Z","steps":["trace[165358976] 'agreement among raft nodes before linearized reading' (duration: 698.101525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:32.493387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:31.795208Z","time spent":"698.175657ms","remote":"127.0.0.1:36202","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-07T04:04:32.493506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.912841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:04:32.493523Z","caller":"traceutil/trace.go:171","msg":"trace[707361917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"910.942522ms","start":"2026-02-07T04:04:31.582575Z","end":"2026-02-07T04:04:32.493518Z","steps":["trace[707361917] 'agreement among raft nodes before linearized reading' (duration: 910.89288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:32.493539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:31.582569Z","time spent":"910.966542ms","remote":"127.0.0.1:36462","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-07T04:04:32.611351Z","caller":"traceutil/trace.go:171","msg":"trace[1127212440] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"113.995838ms","start":"2026-02-07T04:04:32.497338Z","end":"2026-02-07T04:04:32.611334Z","steps":["trace[1127212440] 'process raft request' (duration: 32.382025ms)","trace[1127212440] 'compare' (duration: 81.15575ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:32.611456Z","caller":"traceutil/trace.go:171","msg":"trace[874579388] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"111.986152ms","start":"2026-02-07T04:04:32.499463Z","end":"2026-02-07T04:04:32.611449Z","steps":["trace[874579388] 'process raft request' (duration: 111.54226ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:32.611522Z","caller":"traceutil/trace.go:171","msg":"trace[938900641] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"110.890662ms","start":"2026-02-07T04:04:32.500627Z","end":"2026-02-07T04:04:32.611517Z","steps":["trace[938900641] 'process raft request' (duration: 110.412809ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:32.611593Z","caller":"traceutil/trace.go:171","msg":"trace[793997428] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"110.277125ms","start":"2026-02-07T04:04:32.501311Z","end":"2026-02-07T04:04:32.611588Z","steps":["trace[793997428] 'process raft request' (duration: 109.74844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:33.096228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.787085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:33.096277Z","caller":"traceutil/trace.go:171","msg":"trace[308387352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"300.871058ms","start":"2026-02-07T04:04:32.795389Z","end":"2026-02-07T04:04:33.09626Z","steps":["trace[308387352] 'range keys from in-memory index tree' (duration: 300.739024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:33.096298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:32.795377Z","time spent":"300.916519ms","remote":"127.0.0.1:36202","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-07T04:04:34.830023Z","caller":"traceutil/trace.go:171","msg":"trace[470469430] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"331.756479ms","start":"2026-02-07T04:04:34.498214Z","end":"2026-02-07T04:04:34.82997Z","steps":["trace[470469430] 'read index received' (duration: 331.620265ms)","trace[470469430] 'applied index is now lower than readState.Index' (duration: 135.134µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:34.830044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:34.305472Z","time spent":"524.565231ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-07T04:04:34.830229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.995625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:04:34.830262Z","caller":"traceutil/trace.go:171","msg":"trace[797268400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"332.080517ms","start":"2026-02-07T04:04:34.498171Z","end":"2026-02-07T04:04:34.830252Z","steps":["trace[797268400] 'agreement among raft nodes before linearized reading' (duration: 331.944484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:34.830292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:34.498155Z","time spent":"332.129669ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:04:34.830466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.151776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:34.830495Z","caller":"traceutil/trace.go:171","msg":"trace[146942913] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:737; }","duration":"267.224068ms","start":"2026-02-07T04:04:34.563259Z","end":"2026-02-07T04:04:34.830484Z","steps":["trace[146942913] 'agreement among raft nodes before linearized reading' (duration: 267.158536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:34.830676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.482299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:04:34.830711Z","caller":"traceutil/trace.go:171","msg":"trace[696862480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:737; }","duration":"215.532391ms","start":"2026-02-07T04:04:34.615167Z","end":"2026-02-07T04:04:34.8307Z","steps":["trace[696862480] 'agreement among raft nodes before linearized reading' (duration: 215.446728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:34.830744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.600063ms","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-07T04:04:34.830764Z","caller":"traceutil/trace.go:171","msg":"trace[1611494295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"215.644243ms","start":"2026-02-07T04:04:34.615113Z","end":"2026-02-07T04:04:34.830758Z","steps":["trace[1611494295] 'agreement among raft nodes before linearized reading' (duration: 215.565521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:34.830895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.42925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:04:34.830925Z","caller":"traceutil/trace.go:171","msg":"trace[198290691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:737; }","duration":"214.462321ms","start":"2026-02-07T04:04:34.616455Z","end":"2026-02-07T04:04:34.830917Z","steps":["trace[198290691] 'agreement among raft nodes before linearized reading' (duration: 214.411719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:34.830965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.685945ms","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-07T04:04:34.830984Z","caller":"traceutil/trace.go:171","msg":"trace[158685059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"215.718485ms","start":"2026-02-07T04:04:34.615261Z","end":"2026-02-07T04:04:34.830979Z","steps":["trace[158685059] 'agreement among raft nodes before linearized reading' (duration: 215.669994ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:35.127007Z","caller":"traceutil/trace.go:171","msg":"trace[118232722] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"291.805817ms","start":"2026-02-07T04:04:34.835183Z","end":"2026-02-07T04:04:35.126989Z","steps":["trace[118232722] 'process raft request' (duration: 286.175612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:35.632141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746810,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:04:35.648964Z","caller":"traceutil/trace.go:171","msg":"trace[1023144405] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"809.603445ms","start":"2026-02-07T04:04:34.839339Z","end":"2026-02-07T04:04:35.648942Z","steps":["trace[1023144405] 'process raft request' (duration: 809.568204ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:04:35.649177Z","caller":"traceutil/trace.go:171","msg":"trace[910115005] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"811.294121ms","start":"2026-02-07T04:04:34.837876Z","end":"2026-02-07T04:04:35.64917Z","steps":["trace[910115005] 'process raft request' (duration: 810.676434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:35.649265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:34.837843Z","time spent":"811.364554ms","remote":"127.0.0.1:36462","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-07T04:04:35.64935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:34.839331Z","time spent":"809.920664ms","remote":"127.0.0.1:36462","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-07T04:04:35.649489Z","caller":"traceutil/trace.go:171","msg":"trace[17792695] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"811.272111ms","start":"2026-02-07T04:04:34.838212Z","end":"2026-02-07T04:04:35.649484Z","steps":["trace[17792695] 'process raft request' (duration: 810.667744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:35.649522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:34.838207Z","time spent":"811.300772ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:35.649564Z","caller":"traceutil/trace.go:171","msg":"trace[135106063] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"811.413835ms","start":"2026-02-07T04:04:34.838146Z","end":"2026-02-07T04:04:35.64956Z","steps":["trace[135106063] 'process raft request' (duration: 810.657324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:35.649587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:34.838139Z","time spent":"811.437746ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:35.648988Z","caller":"traceutil/trace.go:171","msg":"trace[1092114020] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"810.979903ms","start":"2026-02-07T04:04:34.837977Z","end":"2026-02-07T04:04:35.648957Z","steps":["trace[1092114020] 'process raft request' (duration: 810.773767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:35.64969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:34.837968Z","time spent":"811.709424ms","remote":"127.0.0.1:36462","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-07T04:04:36.146889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.352214ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:36.147009Z","caller":"traceutil/trace.go:171","msg":"trace[1039871489] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:743; }","duration":"153.486118ms","start":"2026-02-07T04:04:35.993509Z","end":"2026-02-07T04:04:36.146995Z","steps":["trace[1039871489] 'range keys from in-memory index tree' (duration: 153.339524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:36.147065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.312181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:04:36.14714Z","caller":"traceutil/trace.go:171","msg":"trace[1986524739] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"1.01516308s","start":"2026-02-07T04:04:35.131963Z","end":"2026-02-07T04:04:36.147126Z","steps":["trace[1986524739] 'read index received' (duration: 516.968195ms)","trace[1986524739] 'applied index is now lower than readState.Index' (duration: 498.193865ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:36.147343Z","caller":"traceutil/trace.go:171","msg":"trace[1976130572] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"827.90184ms","start":"2026-02-07T04:04:35.319431Z","end":"2026-02-07T04:04:36.147333Z","steps":["trace[1976130572] 'process raft request' (duration: 686.247989ms)","trace[1976130572] 'compare' (duration: 141.228399ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:36.147354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015386236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-07T04:04:36.147389Z","caller":"traceutil/trace.go:171","msg":"trace[580408655] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:744; }","duration":"1.015445828s","start":"2026-02-07T04:04:35.131931Z","end":"2026-02-07T04:04:36.147377Z","steps":["trace[580408655] 'agreement among raft nodes before linearized reading' (duration: 1.015234912s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:36.147419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:35.131915Z","time spent":"1.015497789s","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-07T04:04:36.147436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:35.319414Z","time spent":"827.953991ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:04:48.216925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837746992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:04:48.291271Z","caller":"traceutil/trace.go:171","msg":"trace[1137841782] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"574.824651ms","start":"2026-02-07T04:04:47.716432Z","end":"2026-02-07T04:04:48.291257Z","steps":["trace[1137841782] 'read index received' (duration: 574.718928ms)","trace[1137841782] 'applied index is now lower than readState.Index' (duration: 105.243µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:04:48.291409Z","caller":"traceutil/trace.go:171","msg":"trace[1544768568] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"582.941856ms","start":"2026-02-07T04:04:47.708462Z","end":"2026-02-07T04:04:48.291403Z","steps":["trace[1544768568] 'process raft request' (duration: 582.72459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:48.291469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:47.708452Z","time spent":"582.970607ms","remote":"127.0.0.1:36462","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-07T04:04:48.2916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.165992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:04:48.291618Z","caller":"traceutil/trace.go:171","msg":"trace[1300669153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:824; }","duration":"575.182302ms","start":"2026-02-07T04:04:47.71643Z","end":"2026-02-07T04:04:48.291612Z","steps":["trace[1300669153] 'agreement among raft nodes before linearized reading' (duration: 575.121041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:48.291636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:47.716406Z","time spent":"575.224863ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:04:48.291714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.67494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:04:48.291728Z","caller":"traceutil/trace.go:171","msg":"trace[690444914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:824; }","duration":"552.709661ms","start":"2026-02-07T04:04:47.739013Z","end":"2026-02-07T04:04:48.291722Z","steps":["trace[690444914] 'agreement among raft nodes before linearized reading' (duration: 552.67422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:48.29174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:47.739001Z","time spent":"552.736172ms","remote":"127.0.0.1:36462","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":"warn","ts":"2026-02-07T04:04:48.291824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.945302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:48.291838Z","caller":"traceutil/trace.go:171","msg":"trace[49158164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"496.993523ms","start":"2026-02-07T04:04:47.794841Z","end":"2026-02-07T04:04:48.291835Z","steps":["trace[49158164] 'agreement among raft nodes before linearized reading' (duration: 496.972063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:48.291849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:47.794816Z","time spent":"497.030534ms","remote":"127.0.0.1:36206","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-07T04:04:48.291935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.392203ms","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-07T04:04:48.291946Z","caller":"traceutil/trace.go:171","msg":"trace[1319479025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"552.410263ms","start":"2026-02-07T04:04:47.739533Z","end":"2026-02-07T04:04:48.291943Z","steps":["trace[1319479025] 'agreement among raft nodes before linearized reading' (duration: 552.374722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:48.291957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:47.73953Z","time spent":"552.424854ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:04:48.292022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.511675ms","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-07T04:04:48.292033Z","caller":"traceutil/trace.go:171","msg":"trace[377455348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:824; }","duration":"552.531377ms","start":"2026-02-07T04:04:47.739498Z","end":"2026-02-07T04:04:48.292029Z","steps":["trace[377455348] 'agreement among raft nodes before linearized reading' (duration: 552.500876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:48.292043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:47.739493Z","time spent":"552.547687ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T04:04:49.320319Z","caller":"traceutil/trace.go:171","msg":"trace[289351996] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"513.582511ms","start":"2026-02-07T04:04:48.806717Z","end":"2026-02-07T04:04:49.320299Z","steps":["trace[289351996] 'process raft request' (duration: 513.430296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:49.320425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:48.806703Z","time spent":"513.667383ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:04:50.812073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837747002,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:04:51.609143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.496645ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T04:04:51.609242Z","caller":"traceutil/trace.go:171","msg":"trace[1442903295] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"1.297843225s","start":"2026-02-07T04:04:50.311383Z","end":"2026-02-07T04:04:51.609226Z","steps":["trace[1442903295] 'read index received' (duration: 791.246437ms)","trace[1442903295] 'applied index is now lower than readState.Index' (duration: 506.595368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:04:51.609514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240538883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:04:51.609556Z","caller":"traceutil/trace.go:171","msg":"trace[1989708007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:829; }","duration":"1.240610445s","start":"2026-02-07T04:04:50.368934Z","end":"2026-02-07T04:04:51.609544Z","steps":["trace[1989708007] 'agreement among raft nodes before linearized reading' (duration: 1.24042269s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.609588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.368922Z","time spent":"1.240658256s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:04:51.609787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.219281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:51.609816Z","caller":"traceutil/trace.go:171","msg":"trace[699803600] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:829; }","duration":"672.280712ms","start":"2026-02-07T04:04:50.937526Z","end":"2026-02-07T04:04:51.609806Z","steps":["trace[699803600] 'agreement among raft nodes before linearized reading' (duration: 672.229881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.609845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.937512Z","time spent":"672.325654ms","remote":"127.0.0.1:36412","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-07T04:04:51.609976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.324199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:04:51.610037Z","caller":"traceutil/trace.go:171","msg":"trace[1117710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:829; }","duration":"812.38334ms","start":"2026-02-07T04:04:50.797646Z","end":"2026-02-07T04:04:51.610029Z","steps":["trace[1117710] 'agreement among raft nodes before linearized reading' (duration: 812.309618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.610062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.79761Z","time spent":"812.447082ms","remote":"127.0.0.1:36202","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-07T04:04:51.610235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235211916s","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-07T04:04:51.610261Z","caller":"traceutil/trace.go:171","msg":"trace[1662621016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:829; }","duration":"1.235247146s","start":"2026-02-07T04:04:50.375006Z","end":"2026-02-07T04:04:51.610253Z","steps":["trace[1662621016] 'agreement among raft nodes before linearized reading' (duration: 1.235167824s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.610285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.374999Z","time spent":"1.235280728s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:04:51.610467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235894015s","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-07T04:04:51.610494Z","caller":"traceutil/trace.go:171","msg":"trace[429819759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:829; }","duration":"1.235920836s","start":"2026-02-07T04:04:50.374565Z","end":"2026-02-07T04:04:51.610486Z","steps":["trace[429819759] 'agreement among raft nodes before linearized reading' (duration: 1.235841574s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.610518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.374525Z","time spent":"1.235987668s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:04:51.610687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236515512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:04:51.610713Z","caller":"traceutil/trace.go:171","msg":"trace[1707905413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:829; }","duration":"1.236550883s","start":"2026-02-07T04:04:50.374154Z","end":"2026-02-07T04:04:51.610705Z","steps":["trace[1707905413] 'agreement among raft nodes before linearized reading' (duration: 1.236464081s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.610738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.37415Z","time spent":"1.236582304s","remote":"127.0.0.1:36462","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":"warn","ts":"2026-02-07T04:04:51.611727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300335734s","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-07T04:04:51.61178Z","caller":"traceutil/trace.go:171","msg":"trace[2087422128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:829; }","duration":"1.300407306s","start":"2026-02-07T04:04:50.31136Z","end":"2026-02-07T04:04:51.611768Z","steps":["trace[2087422128] 'agreement among raft nodes before linearized reading' (duration: 1.297973529s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:51.611809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:50.311348Z","time spent":"1.300453657s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-07T04:04:52.074807Z","caller":"traceutil/trace.go:171","msg":"trace[1348142284] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"456.6981ms","start":"2026-02-07T04:04:51.618094Z","end":"2026-02-07T04:04:52.074792Z","steps":["trace[1348142284] 'process raft request' (duration: 452.364161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:52.074895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:51.618077Z","time spent":"456.769942ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:52.075016Z","caller":"traceutil/trace.go:171","msg":"trace[1668020773] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"456.166395ms","start":"2026-02-07T04:04:51.618844Z","end":"2026-02-07T04:04:52.07501Z","steps":["trace[1668020773] 'process raft request' (duration: 455.926419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:52.075047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:51.618834Z","time spent":"456.195126ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:04:52.075163Z","caller":"traceutil/trace.go:171","msg":"trace[225192269] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"454.438298ms","start":"2026-02-07T04:04:51.62072Z","end":"2026-02-07T04:04:52.075158Z","steps":["trace[225192269] 'process raft request' (duration: 454.266003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:52.075189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:51.620704Z","time spent":"454.469949ms","remote":"127.0.0.1:36462","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-07T04:04:52.077071Z","caller":"traceutil/trace.go:171","msg":"trace[1336689025] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"456.31424ms","start":"2026-02-07T04:04:51.620741Z","end":"2026-02-07T04:04:52.077056Z","steps":["trace[1336689025] 'process raft request' (duration: 454.398327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:04:52.077117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:51.620726Z","time spent":"456.362761ms","remote":"127.0.0.1:36462","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-07T04:04:59.811775Z","caller":"traceutil/trace.go:171","msg":"trace[2131559946] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"240.959353ms","start":"2026-02-07T04:04:59.570796Z","end":"2026-02-07T04:04:59.811756Z","steps":["trace[2131559946] 'process raft request' (duration: 240.820009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.524347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.195768ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T04:05:00.524451Z","caller":"traceutil/trace.go:171","msg":"trace[1038769816] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"809.171412ms","start":"2026-02-07T04:04:59.71526Z","end":"2026-02-07T04:05:00.524432Z","steps":["trace[1038769816] 'read index received' (duration: 97.207764ms)","trace[1038769816] 'applied index is now lower than readState.Index' (duration: 711.962658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:00.524875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.611774ms","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-07T04:05:00.524904Z","caller":"traceutil/trace.go:171","msg":"trace[1307004251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"809.669136ms","start":"2026-02-07T04:04:59.715226Z","end":"2026-02-07T04:05:00.524895Z","steps":["trace[1307004251] 'agreement among raft nodes before linearized reading' (duration: 809.547393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.524927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:59.715212Z","time spent":"809.708447ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:05:00.525069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.545421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:00.525091Z","caller":"traceutil/trace.go:171","msg":"trace[1858514872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"730.618803ms","start":"2026-02-07T04:04:59.794467Z","end":"2026-02-07T04:05:00.525086Z","steps":["trace[1858514872] 'agreement among raft nodes before linearized reading' (duration: 730.584543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.525112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:04:59.794449Z","time spent":"730.659224ms","remote":"127.0.0.1:36202","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-07T04:05:00.525271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.459439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-07T04:05:00.525294Z","caller":"traceutil/trace.go:171","msg":"trace[664889318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:852; }","duration":"332.499671ms","start":"2026-02-07T04:05:00.192789Z","end":"2026-02-07T04:05:00.525288Z","steps":["trace[664889318] 'agreement among raft nodes before linearized reading' (duration: 332.439899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.525314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.192782Z","time spent":"332.528412ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:05:00.525466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.204678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-07T04:05:00.5255Z","caller":"traceutil/trace.go:171","msg":"trace[1976609524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"338.25619ms","start":"2026-02-07T04:05:00.187239Z","end":"2026-02-07T04:05:00.525495Z","steps":["trace[1976609524] 'agreement among raft nodes before linearized reading' (duration: 338.166447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.525523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.187228Z","time spent":"338.29016ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:05:00.525629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.561184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T04:05:00.525649Z","caller":"traceutil/trace.go:171","msg":"trace[1725005088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"390.581365ms","start":"2026-02-07T04:05:00.135063Z","end":"2026-02-07T04:05:00.525644Z","steps":["trace[1725005088] 'agreement among raft nodes before linearized reading' (duration: 390.532664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.525668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.135025Z","time spent":"390.639886ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:05:00.525779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.209193ms","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-07T04:05:00.525799Z","caller":"traceutil/trace.go:171","msg":"trace[896163708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"395.246193ms","start":"2026-02-07T04:05:00.130548Z","end":"2026-02-07T04:05:00.525794Z","steps":["trace[896163708] 'agreement among raft nodes before linearized reading' (duration: 395.187221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:00.525819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.130533Z","time spent":"395.282415ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:05:01.295938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837747065,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T04:05:01.42466Z","caller":"traceutil/trace.go:171","msg":"trace[447420126] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:888; }","duration":"629.514432ms","start":"2026-02-07T04:05:00.795131Z","end":"2026-02-07T04:05:01.424645Z","steps":["trace[447420126] 'read index received' (duration: 629.329297ms)","trace[447420126] 'applied index is now lower than readState.Index' (duration: 184.645µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:01.424742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.600734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:01.424758Z","caller":"traceutil/trace.go:171","msg":"trace[121237950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"629.629965ms","start":"2026-02-07T04:05:00.795122Z","end":"2026-02-07T04:05:01.424752Z","steps":["trace[121237950] 'agreement among raft nodes before linearized reading' (duration: 629.577073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:01.424775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.795081Z","time spent":"629.691087ms","remote":"127.0.0.1:36202","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-07T04:05:01.424975Z","caller":"traceutil/trace.go:171","msg":"trace[1263202716] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"891.951649ms","start":"2026-02-07T04:05:00.533018Z","end":"2026-02-07T04:05:01.424969Z","steps":["trace[1263202716] 'process raft request' (duration: 891.490286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:01.425042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.533008Z","time spent":"891.985299ms","remote":"127.0.0.1:36462","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-07T04:05:01.425102Z","caller":"traceutil/trace.go:171","msg":"trace[266463443] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"892.044801ms","start":"2026-02-07T04:05:00.533053Z","end":"2026-02-07T04:05:01.425098Z","steps":["trace[266463443] 'process raft request' (duration: 891.542427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:01.425125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.533048Z","time spent":"892.063102ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:01.425186Z","caller":"traceutil/trace.go:171","msg":"trace[1582110603] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"891.137896ms","start":"2026-02-07T04:05:00.534044Z","end":"2026-02-07T04:05:01.425182Z","steps":["trace[1582110603] 'process raft request' (duration: 890.57024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:01.425208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.534038Z","time spent":"891.156616ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:01.425259Z","caller":"traceutil/trace.go:171","msg":"trace[1683633920] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"891.180197ms","start":"2026-02-07T04:05:00.534076Z","end":"2026-02-07T04:05:01.425256Z","steps":["trace[1683633920] 'process raft request' (duration: 890.551979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:01.42528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:00.534073Z","time spent":"891.195787ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:02.420449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.144806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:02.420511Z","caller":"traceutil/trace.go:171","msg":"trace[1432836708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"623.241598ms","start":"2026-02-07T04:05:01.797244Z","end":"2026-02-07T04:05:02.420486Z","steps":["trace[1432836708] 'range keys from in-memory index tree' (duration: 623.066113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:02.420538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:01.797224Z","time spent":"623.30432ms","remote":"127.0.0.1:36202","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-07T04:05:03.59197Z","caller":"traceutil/trace.go:171","msg":"trace[1408022661] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"159.916146ms","start":"2026-02-07T04:05:03.432037Z","end":"2026-02-07T04:05:03.591953Z","steps":["trace[1408022661] 'process raft request' (duration: 159.832464ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:03.704784Z","caller":"traceutil/trace.go:171","msg":"trace[1677612142] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"272.640078ms","start":"2026-02-07T04:05:03.432128Z","end":"2026-02-07T04:05:03.704768Z","steps":["trace[1677612142] 'process raft request' (duration: 272.476513ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:03.705117Z","caller":"traceutil/trace.go:171","msg":"trace[1016628359] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"272.197355ms","start":"2026-02-07T04:05:03.432913Z","end":"2026-02-07T04:05:03.70511Z","steps":["trace[1016628359] 'process raft request' (duration: 271.804184ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:03.705237Z","caller":"traceutil/trace.go:171","msg":"trace[986892562] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"266.808117ms","start":"2026-02-07T04:05:03.438424Z","end":"2026-02-07T04:05:03.705232Z","steps":["trace[986892562] 'process raft request' (duration: 266.314113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:04.765467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.192955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:05:04.765527Z","caller":"traceutil/trace.go:171","msg":"trace[1798335719] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"136.788527ms","start":"2026-02-07T04:05:04.628727Z","end":"2026-02-07T04:05:04.765515Z","steps":["trace[1798335719] 'read index received' (duration: 27.591µs)","trace[1798335719] 'applied index is now lower than readState.Index' (duration: 136.760256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:04.765631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.90697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:05:04.765651Z","caller":"traceutil/trace.go:171","msg":"trace[7327309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:863; }","duration":"136.943791ms","start":"2026-02-07T04:05:04.628696Z","end":"2026-02-07T04:05:04.765639Z","steps":["trace[7327309] 'agreement among raft nodes before linearized reading' (duration: 136.846188ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:04.765767Z","caller":"traceutil/trace.go:171","msg":"trace[586838191] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"411.538423ms","start":"2026-02-07T04:05:04.354223Z","end":"2026-02-07T04:05:04.765761Z","steps":["trace[586838191] 'process raft request' (duration: 239.00655ms)","trace[586838191] 'compare' (duration: 171.885276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:04.765799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:04.354212Z","time spent":"411.570434ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:06.912203Z","caller":"traceutil/trace.go:171","msg":"trace[1317845155] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"118.322817ms","start":"2026-02-07T04:05:06.793864Z","end":"2026-02-07T04:05:06.912187Z","steps":["trace[1317845155] 'read index received' (duration: 118.204674ms)","trace[1317845155] 'applied index is now lower than readState.Index' (duration: 117.533µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:06.91228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.397499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:06.912297Z","caller":"traceutil/trace.go:171","msg":"trace[1746472979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"118.4464ms","start":"2026-02-07T04:05:06.793845Z","end":"2026-02-07T04:05:06.912291Z","steps":["trace[1746472979] 'agreement among raft nodes before linearized reading' (duration: 118.399229ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:06.912422Z","caller":"traceutil/trace.go:171","msg":"trace[364806581] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"132.256842ms","start":"2026-02-07T04:05:06.780137Z","end":"2026-02-07T04:05:06.912394Z","steps":["trace[364806581] 'process raft request' (duration: 131.939043ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:07.855212Z","caller":"traceutil/trace.go:171","msg":"trace[1424966198] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"139.736338ms","start":"2026-02-07T04:05:07.715462Z","end":"2026-02-07T04:05:07.855198Z","steps":["trace[1424966198] 'read index received' (duration: 139.612895ms)","trace[1424966198] 'applied index is now lower than readState.Index' (duration: 122.913µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:07.85539Z","caller":"traceutil/trace.go:171","msg":"trace[695998478] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"253.979963ms","start":"2026-02-07T04:05:07.601403Z","end":"2026-02-07T04:05:07.855383Z","steps":["trace[695998478] 'process raft request' (duration: 253.713366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:07.855721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.247702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:05:07.855736Z","caller":"traceutil/trace.go:171","msg":"trace[1193329308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:871; }","duration":"140.297854ms","start":"2026-02-07T04:05:07.715434Z","end":"2026-02-07T04:05:07.855732Z","steps":["trace[1193329308] 'agreement among raft nodes before linearized reading' (duration: 140.227252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:07.855817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.416544ms","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-07T04:05:07.855828Z","caller":"traceutil/trace.go:171","msg":"trace[735771434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:871; }","duration":"137.445555ms","start":"2026-02-07T04:05:07.718379Z","end":"2026-02-07T04:05:07.855825Z","steps":["trace[735771434] 'agreement among raft nodes before linearized reading' (duration: 137.412324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:07.855926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.221219ms","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-07T04:05:07.855938Z","caller":"traceutil/trace.go:171","msg":"trace[764147152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:871; }","duration":"137.24194ms","start":"2026-02-07T04:05:07.718693Z","end":"2026-02-07T04:05:07.855935Z","steps":["trace[764147152] 'agreement among raft nodes before linearized reading' (duration: 137.206709ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:07.977071Z","caller":"traceutil/trace.go:171","msg":"trace[1612751964] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"116.61121ms","start":"2026-02-07T04:05:07.86044Z","end":"2026-02-07T04:05:07.977051Z","steps":["trace[1612751964] 'process raft request' (duration: 35.118249ms)","trace[1612751964] 'compare' (duration: 81.383548ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:07.978159Z","caller":"traceutil/trace.go:171","msg":"trace[786435422] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"116.503647ms","start":"2026-02-07T04:05:07.861649Z","end":"2026-02-07T04:05:07.978152Z","steps":["trace[786435422] 'process raft request' (duration: 116.480506ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:07.97831Z","caller":"traceutil/trace.go:171","msg":"trace[180061471] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"117.810173ms","start":"2026-02-07T04:05:07.860494Z","end":"2026-02-07T04:05:07.978304Z","steps":["trace[180061471] 'process raft request' (duration: 117.598807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:10.006177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.044815ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T04:05:10.006395Z","caller":"traceutil/trace.go:171","msg":"trace[22028825] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"145.195048ms","start":"2026-02-07T04:05:09.861188Z","end":"2026-02-07T04:05:10.006383Z","steps":["trace[22028825] 'read index received' (duration: 37.551µs)","trace[22028825] 'applied index is now lower than readState.Index' (duration: 145.156887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:10.006484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.29075ms","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-07T04:05:10.006502Z","caller":"traceutil/trace.go:171","msg":"trace[507995579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:875; }","duration":"145.340962ms","start":"2026-02-07T04:05:09.861156Z","end":"2026-02-07T04:05:10.006497Z","steps":["trace[507995579] 'agreement among raft nodes before linearized reading' (duration: 145.27814ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:10.006926Z","caller":"traceutil/trace.go:171","msg":"trace[1325869755] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"115.53682ms","start":"2026-02-07T04:05:09.891383Z","end":"2026-02-07T04:05:10.00692Z","steps":["trace[1325869755] 'process raft request' (duration: 114.862291ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:10.392428Z","caller":"traceutil/trace.go:171","msg":"trace[1664924908] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"381.519485ms","start":"2026-02-07T04:05:10.010893Z","end":"2026-02-07T04:05:10.392413Z","steps":["trace[1664924908] 'process raft request' (duration: 381.485874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:10.392564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:10.010887Z","time spent":"381.592417ms","remote":"127.0.0.1:36462","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-07T04:05:10.392689Z","caller":"traceutil/trace.go:171","msg":"trace[1489781291] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"383.17198ms","start":"2026-02-07T04:05:10.009495Z","end":"2026-02-07T04:05:10.392667Z","steps":["trace[1489781291] 'process raft request' (duration: 382.776109ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:10.392789Z","caller":"traceutil/trace.go:171","msg":"trace[580397471] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"382.681307ms","start":"2026-02-07T04:05:10.010097Z","end":"2026-02-07T04:05:10.392778Z","steps":["trace[580397471] 'process raft request' (duration: 382.265285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:10.392847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:10.01009Z","time spent":"382.726268ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:10.392792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:10.009489Z","time spent":"383.244722ms","remote":"127.0.0.1:36462","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-07T04:05:12.894374Z","caller":"traceutil/trace.go:171","msg":"trace[552977367] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"490.939476ms","start":"2026-02-07T04:05:12.403419Z","end":"2026-02-07T04:05:12.894359Z","steps":["trace[552977367] 'process raft request' (duration: 490.911445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:12.894476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:12.403401Z","time spent":"491.020148ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:05:12.894645Z","caller":"traceutil/trace.go:171","msg":"trace[129692170] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"492.52158ms","start":"2026-02-07T04:05:12.402119Z","end":"2026-02-07T04:05:12.894641Z","steps":["trace[129692170] 'process raft request' (duration: 491.953044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:12.894676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:12.402113Z","time spent":"492.549401ms","remote":"127.0.0.1:36462","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-07T04:05:14.371297Z","caller":"traceutil/trace.go:171","msg":"trace[469122203] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"186.976772ms","start":"2026-02-07T04:05:14.183981Z","end":"2026-02-07T04:05:14.370958Z","steps":["trace[469122203] 'process raft request' (duration: 105.060271ms)","trace[469122203] 'compare' (duration: 81.774677ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:14.371998Z","caller":"traceutil/trace.go:171","msg":"trace[1829454247] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"187.97038ms","start":"2026-02-07T04:05:14.18402Z","end":"2026-02-07T04:05:14.37199Z","steps":["trace[1829454247] 'process raft request' (duration: 187.63603ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:14.372177Z","caller":"traceutil/trace.go:171","msg":"trace[1109038420] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"170.565441ms","start":"2026-02-07T04:05:14.201605Z","end":"2026-02-07T04:05:14.372171Z","steps":["trace[1109038420] 'process raft request' (duration: 170.101268ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:15.033315Z","caller":"traceutil/trace.go:171","msg":"trace[1499127077] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"133.968089ms","start":"2026-02-07T04:05:14.899323Z","end":"2026-02-07T04:05:15.033291Z","steps":["trace[1499127077] 'process raft request' (duration: 133.828246ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:15.083353Z","caller":"traceutil/trace.go:171","msg":"trace[1092761643] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"183.143037ms","start":"2026-02-07T04:05:14.900181Z","end":"2026-02-07T04:05:15.083324Z","steps":["trace[1092761643] 'process raft request' (duration: 182.353095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.447804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.108968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:05:20.447902Z","caller":"traceutil/trace.go:171","msg":"trace[904861280] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"296.523936ms","start":"2026-02-07T04:05:20.151364Z","end":"2026-02-07T04:05:20.447888Z","steps":["trace[904861280] 'read index received' (duration: 27.324456ms)","trace[904861280] 'applied index is now lower than readState.Index' (duration: 269.19851ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:05:20.448153Z","caller":"traceutil/trace.go:171","msg":"trace[1728932244] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"364.874049ms","start":"2026-02-07T04:05:20.083265Z","end":"2026-02-07T04:05:20.448139Z","steps":["trace[1728932244] 'process raft request' (duration: 95.36059ms)","trace[1728932244] 'compare' (duration: 268.930223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:20.448212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:20.083238Z","time spent":"364.943651ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:05:20.448387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.01808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:05:20.448416Z","caller":"traceutil/trace.go:171","msg":"trace[657107245] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:940; }","duration":"297.083762ms","start":"2026-02-07T04:05:20.151323Z","end":"2026-02-07T04:05:20.448406Z","steps":["trace[657107245] 'agreement among raft nodes before linearized reading' (duration: 297.034151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.448563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.971358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:05:20.448591Z","caller":"traceutil/trace.go:171","msg":"trace[1449881785] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:940; }","duration":"171.03537ms","start":"2026-02-07T04:05:20.277548Z","end":"2026-02-07T04:05:20.448583Z","steps":["trace[1449881785] 'agreement among raft nodes before linearized reading' (duration: 170.989499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:20.448844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.652469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-07T04:05:20.448871Z","caller":"traceutil/trace.go:171","msg":"trace[1970783945] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:940; }","duration":"170.704011ms","start":"2026-02-07T04:05:20.278159Z","end":"2026-02-07T04:05:20.448863Z","steps":["trace[1970783945] 'agreement among raft nodes before linearized reading' (duration: 170.604668ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:26.669375Z","caller":"traceutil/trace.go:171","msg":"trace[84106291] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"265.989649ms","start":"2026-02-07T04:05:26.403363Z","end":"2026-02-07T04:05:26.669353Z","steps":["trace[84106291] 'process raft request' (duration: 265.847905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:26.670127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.295032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:05:26.67018Z","caller":"traceutil/trace.go:171","msg":"trace[1261767518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"149.397115ms","start":"2026-02-07T04:05:26.520773Z","end":"2026-02-07T04:05:26.67017Z","steps":["trace[1261767518] 'agreement among raft nodes before linearized reading' (duration: 149.255491ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:05:26.669942Z","caller":"traceutil/trace.go:171","msg":"trace[580063862] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1007; }","duration":"149.107537ms","start":"2026-02-07T04:05:26.520823Z","end":"2026-02-07T04:05:26.66993Z","steps":["trace[580063862] 'read index received' (duration: 149.102627ms)","trace[580063862] 'applied index is now lower than readState.Index' (duration: 4.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:27.094815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.112689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:05:27.094893Z","caller":"traceutil/trace.go:171","msg":"trace[1456217373] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"419.675136ms","start":"2026-02-07T04:05:26.675197Z","end":"2026-02-07T04:05:27.094872Z","steps":["trace[1456217373] 'process raft request' (duration: 214.452004ms)","trace[1456217373] 'compare' (duration: 204.967315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:05:27.094936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:05:26.675183Z","time spent":"419.735818ms","remote":"127.0.0.1:36462","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-07T04:05:27.936076Z","caller":"traceutil/trace.go:171","msg":"trace[1064107283] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"113.904796ms","start":"2026-02-07T04:05:27.822143Z","end":"2026-02-07T04:05:27.936048Z","steps":["trace[1064107283] 'process raft request' (duration: 113.756382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:05:28.319953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.33024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T04:05:28.320972Z","caller":"traceutil/trace.go:171","msg":"trace[791762696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"209.388521ms","start":"2026-02-07T04:05:28.111565Z","end":"2026-02-07T04:05:28.320953Z","steps":["trace[791762696] 'range keys from in-memory index tree' (duration: 208.177506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:23.728102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.797043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:23.73709Z","caller":"traceutil/trace.go:171","msg":"trace[1220746283] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"236.245316ms","start":"2026-02-07T04:06:23.50083Z","end":"2026-02-07T04:06:23.737075Z","steps":["trace[1220746283] 'read index received' (duration: 44.421839ms)","trace[1220746283] 'applied index is now lower than readState.Index' (duration: 191.822187ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:23.737136Z","caller":"traceutil/trace.go:171","msg":"trace[1290916447] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"313.8346ms","start":"2026-02-07T04:06:23.423262Z","end":"2026-02-07T04:06:23.737097Z","steps":["trace[1290916447] 'process raft request' (duration: 121.951821ms)","trace[1290916447] 'compare' (duration: 182.69023ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:23.737277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.455793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:06:23.737304Z","caller":"traceutil/trace.go:171","msg":"trace[1929419353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"236.545166ms","start":"2026-02-07T04:06:23.500751Z","end":"2026-02-07T04:06:23.737296Z","steps":["trace[1929419353] 'agreement among raft nodes before linearized reading' (duration: 236.415382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:23.73735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:23.423235Z","time spent":"313.982234ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:06:23.737501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.242656ms","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-07T04:06:23.737522Z","caller":"traceutil/trace.go:171","msg":"trace[1248171269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"145.323449ms","start":"2026-02-07T04:06:23.592193Z","end":"2026-02-07T04:06:23.737517Z","steps":["trace[1248171269] 'agreement among raft nodes before linearized reading' (duration: 145.267267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:23.737619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.662906ms","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-07T04:06:23.737636Z","caller":"traceutil/trace.go:171","msg":"trace[1229175039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"136.720387ms","start":"2026-02-07T04:06:23.600908Z","end":"2026-02-07T04:06:23.737629Z","steps":["trace[1229175039] 'agreement among raft nodes before linearized reading' (duration: 136.674596ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:06:29.034072Z","caller":"traceutil/trace.go:171","msg":"trace[1580022687] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"364.773552ms","start":"2026-02-07T04:06:28.669275Z","end":"2026-02-07T04:06:29.034048Z","steps":["trace[1580022687] 'process raft request' (duration: 364.597877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:29.034553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:06:28.669255Z","time spent":"365.228155ms","remote":"127.0.0.1:36462","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-07T04:06:31.655289Z","caller":"traceutil/trace.go:171","msg":"trace[1911167172] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"146.378718ms","start":"2026-02-07T04:06:31.508895Z","end":"2026-02-07T04:06:31.655273Z","steps":["trace[1911167172] 'read index received' (duration: 146.241515ms)","trace[1911167172] 'applied index is now lower than readState.Index' (duration: 136.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:31.655362Z","caller":"traceutil/trace.go:171","msg":"trace[1929044036] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"181.383274ms","start":"2026-02-07T04:06:31.473971Z","end":"2026-02-07T04:06:31.655354Z","steps":["trace[1929044036] 'process raft request' (duration: 181.207269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:31.655569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.662966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:06:31.65559Z","caller":"traceutil/trace.go:171","msg":"trace[1540055731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"146.753359ms","start":"2026-02-07T04:06:31.508832Z","end":"2026-02-07T04:06:31.655585Z","steps":["trace[1540055731] 'agreement among raft nodes before linearized reading' (duration: 146.662087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:31.655653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.54302ms","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-07T04:06:31.655668Z","caller":"traceutil/trace.go:171","msg":"trace[632876574] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1247; }","duration":"110.604322ms","start":"2026-02-07T04:06:31.545061Z","end":"2026-02-07T04:06:31.655665Z","steps":["trace[632876574] 'agreement among raft nodes before linearized reading' (duration: 110.569111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:38.354897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.712697ms","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:509"} {"level":"info","ts":"2026-02-07T04:06:38.354978Z","caller":"traceutil/trace.go:171","msg":"trace[1559995962] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1276; }","duration":"109.81493ms","start":"2026-02-07T04:06:38.245129Z","end":"2026-02-07T04:06:38.354944Z","steps":["trace[1559995962] 'range keys from in-memory index tree' (duration: 109.596924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:38.355126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.45501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:06:38.356498Z","caller":"traceutil/trace.go:171","msg":"trace[371766405] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1276; }","duration":"146.659366ms","start":"2026-02-07T04:06:38.208534Z","end":"2026-02-07T04:06:38.355194Z","steps":["trace[371766405] 'count revisions from in-memory index tree' (duration: 146.377709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:06:44.404849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.286899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:44.405259Z","caller":"traceutil/trace.go:171","msg":"trace[1800863631] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"284.047632ms","start":"2026-02-07T04:06:44.121193Z","end":"2026-02-07T04:06:44.40524Z","steps":["trace[1800863631] 'process raft request' (duration: 101.29417ms)","trace[1800863631] 'compare' (duration: 182.127965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:06:44.67502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.42134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:06:44.675351Z","caller":"traceutil/trace.go:171","msg":"trace[152961440] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"218.407915ms","start":"2026-02-07T04:06:44.456693Z","end":"2026-02-07T04:06:44.675101Z","steps":["trace[152961440] 'process raft request' (duration: 66.82608ms)","trace[152961440] 'compare' (duration: 151.320267ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:06:56.219572Z","caller":"traceutil/trace.go:171","msg":"trace[948431897] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"127.007003ms","start":"2026-02-07T04:06:56.092548Z","end":"2026-02-07T04:06:56.219555Z","steps":["trace[948431897] 'process raft request' (duration: 103.42035ms)","trace[948431897] 'compare' (duration: 23.490921ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:02.207903Z","caller":"traceutil/trace.go:171","msg":"trace[706985885] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"195.333296ms","start":"2026-02-07T04:07:02.012552Z","end":"2026-02-07T04:07:02.207885Z","steps":["trace[706985885] 'process raft request' (duration: 158.961953ms)","trace[706985885] 'compare' (duration: 36.097565ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:09.634307Z","caller":"traceutil/trace.go:171","msg":"trace[530250466] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"115.791958ms","start":"2026-02-07T04:07:09.518498Z","end":"2026-02-07T04:07:09.63429Z","steps":["trace[530250466] 'process raft request' (duration: 115.688365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:09.941304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.707734ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T04:07:09.941896Z","caller":"traceutil/trace.go:171","msg":"trace[444420694] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1463; }","duration":"144.236668ms","start":"2026-02-07T04:07:09.797641Z","end":"2026-02-07T04:07:09.941878Z","steps":["trace[444420694] 'read index received' (duration: 43.142054ms)","trace[444420694] 'applied index is now lower than readState.Index' (duration: 101.092444ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:09.941984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.332951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:09.942049Z","caller":"traceutil/trace.go:171","msg":"trace[975678705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1403; }","duration":"144.426484ms","start":"2026-02-07T04:07:09.797607Z","end":"2026-02-07T04:07:09.942034Z","steps":["trace[975678705] 'agreement among raft nodes before linearized reading' (duration: 144.325171ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:43.103928Z","caller":"traceutil/trace.go:171","msg":"trace[563797379] linearizableReadLoop","detail":"{readStateIndex:1605; appliedIndex:1604; }","duration":"403.900585ms","start":"2026-02-07T04:07:42.700008Z","end":"2026-02-07T04:07:43.103908Z","steps":["trace[563797379] 'read index received' (duration: 403.749011ms)","trace[563797379] 'applied index is now lower than readState.Index' (duration: 150.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:43.104184Z","caller":"traceutil/trace.go:171","msg":"trace[182217369] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"412.998565ms","start":"2026-02-07T04:07:42.69117Z","end":"2026-02-07T04:07:43.104168Z","steps":["trace[182217369] 'process raft request' (duration: 412.637645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:43.104731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.929862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5912"} {"level":"warn","ts":"2026-02-07T04:07:43.105012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:42.691152Z","time spent":"413.065418ms","remote":"127.0.0.1:36462","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-07T04:07:43.104783Z","caller":"traceutil/trace.go:171","msg":"trace[825048415] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1538; }","duration":"262.007114ms","start":"2026-02-07T04:07:42.842765Z","end":"2026-02-07T04:07:43.104772Z","steps":["trace[825048415] 'agreement among raft nodes before linearized reading' (duration: 261.801328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:43.106052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.034014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:07:43.106097Z","caller":"traceutil/trace.go:171","msg":"trace[1062802930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1538; }","duration":"406.097706ms","start":"2026-02-07T04:07:42.699986Z","end":"2026-02-07T04:07:43.106084Z","steps":["trace[1062802930] 'agreement among raft nodes before linearized reading' (duration: 405.954062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:43.106127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:42.699978Z","time spent":"406.142067ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:07:45.203345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837748779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:45.703709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837748779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:46.2044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837748779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:46.704965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837748779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:46.798314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000643401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-07T04:07:46.80036Z","caller":"traceutil/trace.go:171","msg":"trace[2005052621] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.002724029s","start":"2026-02-07T04:07:44.797613Z","end":"2026-02-07T04:07:46.800337Z","steps":["trace[2005052621] 'agreement among raft nodes before linearized reading' (duration: 2.000660922s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:46.802863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:44.797604Z","time spent":"2.005235659s","remote":"127.0.0.1:36202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/07 04:07:46 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-07T04:07:47.205887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837748779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:07:47.387663Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.679428613s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-07T04:07:47.48763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:44.702208Z","time spent":"2.785418534s","remote":"127.0.0.1:36216","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T04:07:47.64719Z","caller":"traceutil/trace.go:171","msg":"trace[1462736675] linearizableReadLoop","detail":"{readStateIndex:1611; appliedIndex:1610; }","duration":"2.94485576s","start":"2026-02-07T04:07:44.702318Z","end":"2026-02-07T04:07:47.647173Z","steps":["trace[1462736675] 'read index received' (duration: 2.785669221s)","trace[1462736675] 'applied index is now lower than readState.Index' (duration: 159.185829ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:47.647267Z","caller":"traceutil/trace.go:171","msg":"trace[53794975] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"2.943356669s","start":"2026-02-07T04:07:44.703897Z","end":"2026-02-07T04:07:47.647253Z","steps":["trace[53794975] 'process raft request' (duration: 2.937020055s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.647352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:44.703889Z","time spent":"2.94339381s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:07:47.647413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.945074256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T04:07:47.64746Z","caller":"traceutil/trace.go:171","msg":"trace[1179868365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1543; }","duration":"2.945138007s","start":"2026-02-07T04:07:44.702309Z","end":"2026-02-07T04:07:47.647447Z","steps":["trace[1179868365] 'agreement among raft nodes before linearized reading' (duration: 2.944994023s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.647494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:44.702304Z","time spent":"2.94518308s","remote":"127.0.0.1:36462","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-07T04:07:47.877504Z","caller":"traceutil/trace.go:171","msg":"trace[1511710341] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"389.119778ms","start":"2026-02-07T04:07:47.488364Z","end":"2026-02-07T04:07:47.877483Z","steps":["trace[1511710341] 'process raft request' (duration: 293.730618ms)","trace[1511710341] 'compare' (duration: 95.245196ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:47.877525Z","caller":"traceutil/trace.go:171","msg":"trace[959150130] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"225.61544ms","start":"2026-02-07T04:07:47.651894Z","end":"2026-02-07T04:07:47.87751Z","steps":["trace[959150130] 'process raft request' (duration: 225.571729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.877604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.488348Z","time spent":"389.20351ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:47.877737Z","caller":"traceutil/trace.go:171","msg":"trace[1831376500] linearizableReadLoop","detail":"{readStateIndex:1612; appliedIndex:1611; }","duration":"230.494294ms","start":"2026-02-07T04:07:47.647233Z","end":"2026-02-07T04:07:47.877727Z","steps":["trace[1831376500] 'read index received' (duration: 134.870748ms)","trace[1831376500] 'applied index is now lower than readState.Index' (duration: 95.622326ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:47.87785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.926081394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-02-07T04:07:47.877858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.173998447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-07T04:07:47.877875Z","caller":"traceutil/trace.go:171","msg":"trace[1879410738] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1545; }","duration":"1.926132356s","start":"2026-02-07T04:07:45.951734Z","end":"2026-02-07T04:07:47.877866Z","steps":["trace[1879410738] 'agreement among raft nodes before linearized reading' (duration: 1.926027983s)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:47.877884Z","caller":"traceutil/trace.go:171","msg":"trace[753003338] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1545; }","duration":"3.174053519s","start":"2026-02-07T04:07:44.703822Z","end":"2026-02-07T04:07:47.877876Z","steps":["trace[753003338] 'agreement among raft nodes before linearized reading' (duration: 3.173937706s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.877896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.951721Z","time spent":"1.926169677s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:07:47.877934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:44.703811Z","time spent":"3.17409321s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-07T04:07:47.878079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.401814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T04:07:47.878091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.067457517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T04:07:47.878102Z","caller":"traceutil/trace.go:171","msg":"trace[198410749] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1545; }","duration":"394.459255ms","start":"2026-02-07T04:07:47.483635Z","end":"2026-02-07T04:07:47.878094Z","steps":["trace[198410749] 'agreement among raft nodes before linearized reading' (duration: 394.425844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.87812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.48361Z","time spent":"394.505197ms","remote":"127.0.0.1:53850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-02-07T04:07:47.878126Z","caller":"traceutil/trace.go:171","msg":"trace[1299852452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1545; }","duration":"2.067534619s","start":"2026-02-07T04:07:45.810584Z","end":"2026-02-07T04:07:47.878119Z","steps":["trace[1299852452] 'agreement among raft nodes before linearized reading' (duration: 2.067454477s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.878152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.810565Z","time spent":"2.067581871s","remote":"127.0.0.1:36462","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-07T04:07:47.878235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.646092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-07T04:07:47.878252Z","caller":"traceutil/trace.go:171","msg":"trace[1250046292] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1545; }","duration":"829.690974ms","start":"2026-02-07T04:07:47.048556Z","end":"2026-02-07T04:07:47.878247Z","steps":["trace[1250046292] 'agreement among raft nodes before linearized reading' (duration: 829.640262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.878269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:47.048535Z","time spent":"829.729884ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:07:47.878313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.55126986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-02-07T04:07:47.878339Z","caller":"traceutil/trace.go:171","msg":"trace[2054372377] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1545; }","duration":"2.551317071s","start":"2026-02-07T04:07:45.327015Z","end":"2026-02-07T04:07:47.878332Z","steps":["trace[2054372377] 'agreement among raft nodes before linearized reading' (duration: 2.551240319s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.878366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.32699Z","time spent":"2.551371003s","remote":"127.0.0.1:36648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4220,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-07T04:07:47.878527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.751975716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:07:47.878552Z","caller":"traceutil/trace.go:171","msg":"trace[1998544031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1545; }","duration":"2.751999887s","start":"2026-02-07T04:07:45.126545Z","end":"2026-02-07T04:07:47.878545Z","steps":["trace[1998544031] 'agreement among raft nodes before linearized reading' (duration: 2.751932885s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.878577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.126535Z","time spent":"2.752034979s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T04:07:47.878632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.758363072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-07T04:07:47.878346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078723989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:47.878655Z","caller":"traceutil/trace.go:171","msg":"trace[654419151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1545; }","duration":"2.758418634s","start":"2026-02-07T04:07:45.12023Z","end":"2026-02-07T04:07:47.878649Z","steps":["trace[654419151] 'agreement among raft nodes before linearized reading' (duration: 2.758361652s)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:47.878658Z","caller":"traceutil/trace.go:171","msg":"trace[1431763433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1545; }","duration":"1.07907308s","start":"2026-02-07T04:07:46.799577Z","end":"2026-02-07T04:07:47.878651Z","steps":["trace[1431763433] 'agreement among raft nodes before linearized reading' (duration: 1.078754181s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.878673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:45.120215Z","time spent":"2.758453525s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:07:47.878685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:46.799558Z","time spent":"1.079120881s","remote":"127.0.0.1:36206","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-07T04:07:47.878768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.17479315s","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-07T04:07:47.878784Z","caller":"traceutil/trace.go:171","msg":"trace[1655432455] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1545; }","duration":"3.17481673s","start":"2026-02-07T04:07:44.703962Z","end":"2026-02-07T04:07:47.878779Z","steps":["trace[1655432455] 'agreement among raft nodes before linearized reading' (duration: 3.174761569s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:47.8788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:44.70396Z","time spent":"3.174836951s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:07:48.107105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.402716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:07:48.107579Z","caller":"traceutil/trace.go:171","msg":"trace[1662509751] linearizableReadLoop","detail":"{readStateIndex:1618; appliedIndex:1614; }","duration":"209.181557ms","start":"2026-02-07T04:07:47.898382Z","end":"2026-02-07T04:07:48.107563Z","steps":["trace[1662509751] 'read index received' (duration: 97.678033ms)","trace[1662509751] 'applied index is now lower than readState.Index' (duration: 111.502654ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:48.108456Z","caller":"traceutil/trace.go:171","msg":"trace[1153253073] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"219.369518ms","start":"2026-02-07T04:07:47.889068Z","end":"2026-02-07T04:07:48.108438Z","steps":["trace[1153253073] 'process raft request' (duration: 107.040061ms)","trace[1153253073] 'compare' (duration: 109.249642ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:48.108651Z","caller":"traceutil/trace.go:171","msg":"trace[1761141675] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"219.517172ms","start":"2026-02-07T04:07:47.889123Z","end":"2026-02-07T04:07:48.108641Z","steps":["trace[1761141675] 'process raft request' (duration: 218.164814ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:48.108768Z","caller":"traceutil/trace.go:171","msg":"trace[1033294069] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"212.289513ms","start":"2026-02-07T04:07:47.896471Z","end":"2026-02-07T04:07:48.10876Z","steps":["trace[1033294069] 'process raft request' (duration: 210.879284ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:48.108882Z","caller":"traceutil/trace.go:171","msg":"trace[1772669178] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"212.294943ms","start":"2026-02-07T04:07:47.896577Z","end":"2026-02-07T04:07:48.108872Z","steps":["trace[1772669178] 'process raft request' (duration: 210.812402ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:48.109022Z","caller":"traceutil/trace.go:171","msg":"trace[1070244957] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"204.649852ms","start":"2026-02-07T04:07:47.904363Z","end":"2026-02-07T04:07:48.109013Z","steps":["trace[1070244957] 'process raft request' (duration: 203.065608ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:48.109353Z","caller":"traceutil/trace.go:171","msg":"trace[2124967699] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"202.441221ms","start":"2026-02-07T04:07:47.906902Z","end":"2026-02-07T04:07:48.109343Z","steps":["trace[2124967699] 'process raft request' (duration: 200.553409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:48.109572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.180692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:48.109607Z","caller":"traceutil/trace.go:171","msg":"trace[1834547257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1552; }","duration":"211.236244ms","start":"2026-02-07T04:07:47.898361Z","end":"2026-02-07T04:07:48.109597Z","steps":["trace[1834547257] 'agreement among raft nodes before linearized reading' (duration: 211.176243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:48.109747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.522276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-07T04:07:48.109779Z","caller":"traceutil/trace.go:171","msg":"trace[1748949505] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1552; }","duration":"205.578998ms","start":"2026-02-07T04:07:47.904192Z","end":"2026-02-07T04:07:48.109771Z","steps":["trace[1748949505] 'agreement among raft nodes before linearized reading' (duration: 205.523786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:48.113967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.422519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-02-07T04:07:48.114035Z","caller":"traceutil/trace.go:171","msg":"trace[1475411800] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1552; }","duration":"137.528272ms","start":"2026-02-07T04:07:47.976498Z","end":"2026-02-07T04:07:48.114026Z","steps":["trace[1475411800] 'agreement among raft nodes before linearized reading' (duration: 133.648775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:50.711154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.242967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:07:50.711232Z","caller":"traceutil/trace.go:171","msg":"trace[1618448477] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"543.993058ms","start":"2026-02-07T04:07:50.167218Z","end":"2026-02-07T04:07:50.711211Z","steps":["trace[1618448477] 'process raft request' (duration: 300.629359ms)","trace[1618448477] 'compare' (duration: 243.157274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:50.711265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:50.167202Z","time spent":"544.048499ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:50.72709Z","caller":"traceutil/trace.go:171","msg":"trace[1179861880] linearizableReadLoop","detail":"{readStateIndex:1631; appliedIndex:1628; }","duration":"103.749071ms","start":"2026-02-07T04:07:50.623319Z","end":"2026-02-07T04:07:50.727068Z","steps":["trace[1179861880] 'read index received' (duration: 103.524465ms)","trace[1179861880] 'applied index is now lower than readState.Index' (duration: 223.766µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:50.72723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.891524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:07:50.72726Z","caller":"traceutil/trace.go:171","msg":"trace[84163895] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1561; }","duration":"103.967417ms","start":"2026-02-07T04:07:50.623284Z","end":"2026-02-07T04:07:50.727252Z","steps":["trace[84163895] 'agreement among raft nodes before linearized reading' (duration: 103.863974ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:50.727542Z","caller":"traceutil/trace.go:171","msg":"trace[1699708353] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"557.416548ms","start":"2026-02-07T04:07:50.170114Z","end":"2026-02-07T04:07:50.727531Z","steps":["trace[1699708353] 'process raft request' (duration: 556.784001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:50.727624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:50.170108Z","time spent":"557.458459ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:50.727746Z","caller":"traceutil/trace.go:171","msg":"trace[181293261] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"557.042488ms","start":"2026-02-07T04:07:50.170695Z","end":"2026-02-07T04:07:50.727738Z","steps":["trace[181293261] 'process raft request' (duration: 556.301098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:50.727792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:50.170689Z","time spent":"557.076849ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T04:07:50.727895Z","caller":"traceutil/trace.go:171","msg":"trace[677287365] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"217.62154ms","start":"2026-02-07T04:07:50.510265Z","end":"2026-02-07T04:07:50.727886Z","steps":["trace[677287365] 'process raft request' (duration: 216.765857ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:07:54.958605Z","caller":"traceutil/trace.go:171","msg":"trace[795392958] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"192.441746ms","start":"2026-02-07T04:07:54.766142Z","end":"2026-02-07T04:07:54.958584Z","steps":["trace[795392958] 'process raft request' (duration: 161.04104ms)","trace[795392958] 'compare' (duration: 31.099727ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:07:55.282957Z","caller":"traceutil/trace.go:171","msg":"trace[2117680918] linearizableReadLoop","detail":"{readStateIndex:1649; appliedIndex:1646; }","duration":"496.040386ms","start":"2026-02-07T04:07:54.786898Z","end":"2026-02-07T04:07:55.282939Z","steps":["trace[2117680918] 'read index received' (duration: 140.297328ms)","trace[2117680918] 'applied index is now lower than readState.Index' (duration: 355.742248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:07:55.283082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.933178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:07:55.283107Z","caller":"traceutil/trace.go:171","msg":"trace[762348517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1578; }","duration":"489.986749ms","start":"2026-02-07T04:07:54.79311Z","end":"2026-02-07T04:07:55.283096Z","steps":["trace[762348517] 'agreement among raft nodes before linearized reading' (duration: 489.930557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:55.283114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.201771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T04:07:55.283159Z","caller":"traceutil/trace.go:171","msg":"trace[1035657224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1578; }","duration":"496.274292ms","start":"2026-02-07T04:07:54.786873Z","end":"2026-02-07T04:07:55.283147Z","steps":["trace[1035657224] 'agreement among raft nodes before linearized reading' (duration: 496.161889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:55.283173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.43963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-07T04:07:55.283182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:54.786862Z","time spent":"496.315544ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-07T04:07:55.28319Z","caller":"traceutil/trace.go:171","msg":"trace[485318574] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1578; }","duration":"322.480601ms","start":"2026-02-07T04:07:54.960705Z","end":"2026-02-07T04:07:55.283185Z","steps":["trace[485318574] 'agreement among raft nodes before linearized reading' (duration: 322.44534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:55.283207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:54.960693Z","time spent":"322.511162ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-07T04:07:55.283273Z","caller":"traceutil/trace.go:171","msg":"trace[791140829] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"515.334127ms","start":"2026-02-07T04:07:54.767933Z","end":"2026-02-07T04:07:55.283267Z","steps":["trace[791140829] 'process raft request' (duration: 510.950447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:55.283323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:54.767918Z","time spent":"515.368379ms","remote":"127.0.0.1:36462","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-07T04:07:55.283388Z","caller":"traceutil/trace.go:171","msg":"trace[453565407] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"515.38543ms","start":"2026-02-07T04:07:54.767997Z","end":"2026-02-07T04:07:55.283382Z","steps":["trace[453565407] 'process raft request' (duration: 514.901106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:07:55.283415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:54.767993Z","time spent":"515.4047ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:07:55.283129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:07:54.793102Z","time spent":"490.022559ms","remote":"127.0.0.1:36202","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-07T04:08:13.534679Z","caller":"traceutil/trace.go:171","msg":"trace[640479813] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"110.477476ms","start":"2026-02-07T04:08:13.424176Z","end":"2026-02-07T04:08:13.534653Z","steps":["trace[640479813] 'process raft request' (duration: 85.181488ms)","trace[640479813] 'compare' (duration: 25.139373ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:13.534894Z","caller":"traceutil/trace.go:171","msg":"trace[1756966721] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"109.949081ms","start":"2026-02-07T04:08:13.424935Z","end":"2026-02-07T04:08:13.534884Z","steps":["trace[1756966721] 'process raft request' (duration: 109.680694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.76963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.77821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:08:38.769713Z","caller":"traceutil/trace.go:171","msg":"trace[2116504553] linearizableReadLoop","detail":"{readStateIndex:1854; appliedIndex:1853; }","duration":"376.74569ms","start":"2026-02-07T04:08:38.392955Z","end":"2026-02-07T04:08:38.7697Z","steps":["trace[2116504553] 'read index received' (duration: 50.283419ms)","trace[2116504553] 'applied index is now lower than readState.Index' (duration: 326.461121ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:38.769898Z","caller":"traceutil/trace.go:171","msg":"trace[776299376] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"387.374531ms","start":"2026-02-07T04:08:38.382514Z","end":"2026-02-07T04:08:38.769889Z","steps":["trace[776299376] 'process raft request' (duration: 188.263842ms)","trace[776299376] 'compare' (duration: 198.628886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:38.769946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.382501Z","time spent":"387.420362ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:38.770136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.177212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-07T04:08:38.770161Z","caller":"traceutil/trace.go:171","msg":"trace[73109077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1775; }","duration":"377.215082ms","start":"2026-02-07T04:08:38.39294Z","end":"2026-02-07T04:08:38.770155Z","steps":["trace[73109077] 'agreement among raft nodes before linearized reading' (duration: 377.13529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.770179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.392933Z","time spent":"377.242153ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T04:08:38.770305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.10797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T04:08:38.770327Z","caller":"traceutil/trace.go:171","msg":"trace[635476709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1775; }","duration":"311.151501ms","start":"2026-02-07T04:08:38.45917Z","end":"2026-02-07T04:08:38.770322Z","steps":["trace[635476709] 'agreement among raft nodes before linearized reading' (duration: 311.097919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.770344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:38.45916Z","time spent":"311.180441ms","remote":"127.0.0.1:36462","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-07T04:08:38.77047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.425974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:38.770492Z","caller":"traceutil/trace.go:171","msg":"trace[698435598] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1775; }","duration":"216.469185ms","start":"2026-02-07T04:08:38.554018Z","end":"2026-02-07T04:08:38.770487Z","steps":["trace[698435598] 'agreement among raft nodes before linearized reading' (duration: 216.435014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:38.770591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.359613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T04:08:38.770613Z","caller":"traceutil/trace.go:171","msg":"trace[1500458861] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1775; }","duration":"231.421485ms","start":"2026-02-07T04:08:38.539186Z","end":"2026-02-07T04:08:38.770608Z","steps":["trace[1500458861] 'agreement among raft nodes before linearized reading' (duration: 231.383334ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:38.904877Z","caller":"traceutil/trace.go:171","msg":"trace[1671718475] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"107.690342ms","start":"2026-02-07T04:08:38.797169Z","end":"2026-02-07T04:08:38.904859Z","steps":["trace[1671718475] 'read index received' (duration: 35.652217ms)","trace[1671718475] 'applied index is now lower than readState.Index' (duration: 72.037305ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:38.904964Z","caller":"traceutil/trace.go:171","msg":"trace[1097948070] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"128.832832ms","start":"2026-02-07T04:08:38.776124Z","end":"2026-02-07T04:08:38.904956Z","steps":["trace[1097948070] 'process raft request' (duration: 112.365021ms)","trace[1097948070] 'compare' (duration: 16.305157ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:38.90697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.77849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:38.907041Z","caller":"traceutil/trace.go:171","msg":"trace[1840059881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1777; }","duration":"109.888793ms","start":"2026-02-07T04:08:38.797139Z","end":"2026-02-07T04:08:38.907027Z","steps":["trace[1840059881] 'agreement among raft nodes before linearized reading' (duration: 107.894878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:40.359184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.615426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-02-07T04:08:40.359235Z","caller":"traceutil/trace.go:171","msg":"trace[1322857953] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1782; }","duration":"113.725379ms","start":"2026-02-07T04:08:40.245497Z","end":"2026-02-07T04:08:40.359222Z","steps":["trace[1322857953] 'range keys from in-memory index tree' (duration: 113.472531ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:46.069324Z","caller":"traceutil/trace.go:171","msg":"trace[1558352360] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"282.342353ms","start":"2026-02-07T04:08:45.786967Z","end":"2026-02-07T04:08:46.069309Z","steps":["trace[1558352360] 'process raft request' (duration: 281.964162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:47.066122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.298279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T04:08:47.066222Z","caller":"traceutil/trace.go:171","msg":"trace[560401521] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"888.556392ms","start":"2026-02-07T04:08:46.177644Z","end":"2026-02-07T04:08:47.0662Z","steps":["trace[560401521] 'read index received' (duration: 141.049797ms)","trace[560401521] 'applied index is now lower than readState.Index' (duration: 747.505005ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:47.068165Z","caller":"traceutil/trace.go:171","msg":"trace[1513029875] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"996.029439ms","start":"2026-02-07T04:08:46.072101Z","end":"2026-02-07T04:08:47.068131Z","steps":["trace[1513029875] 'process raft request' (duration: 246.639772ms)","trace[1513029875] 'compare' (duration: 747.073153ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:47.068295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:46.072083Z","time spent":"996.158172ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:47.0693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.298417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-07T04:08:47.06936Z","caller":"traceutil/trace.go:171","msg":"trace[294962227] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1809; }","duration":"891.735959ms","start":"2026-02-07T04:08:46.177597Z","end":"2026-02-07T04:08:47.069333Z","steps":["trace[294962227] 'agreement among raft nodes before linearized reading' (duration: 891.241165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:47.069396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:46.177581Z","time spent":"891.804851ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-07T04:08:47.070703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.616909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:08:47.070721Z","caller":"traceutil/trace.go:171","msg":"trace[953549698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1809; }","duration":"878.672331ms","start":"2026-02-07T04:08:46.192043Z","end":"2026-02-07T04:08:47.070715Z","steps":["trace[953549698] 'agreement among raft nodes before linearized reading' (duration: 878.450765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:47.070739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:46.192023Z","time spent":"878.712482ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:08:47.071379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.592854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-07T04:08:47.0714Z","caller":"traceutil/trace.go:171","msg":"trace[2120245181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1809; }","duration":"125.643295ms","start":"2026-02-07T04:08:46.945748Z","end":"2026-02-07T04:08:47.071391Z","steps":["trace[2120245181] 'agreement among raft nodes before linearized reading' (duration: 125.477141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:47.071667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.754906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:47.071687Z","caller":"traceutil/trace.go:171","msg":"trace[748238079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1809; }","duration":"273.806727ms","start":"2026-02-07T04:08:46.79787Z","end":"2026-02-07T04:08:47.071677Z","steps":["trace[748238079] 'agreement among raft nodes before linearized reading' (duration: 273.776566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:47.071907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.91467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-07T04:08:47.071928Z","caller":"traceutil/trace.go:171","msg":"trace[341797169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1809; }","duration":"180.987443ms","start":"2026-02-07T04:08:46.890929Z","end":"2026-02-07T04:08:47.071917Z","steps":["trace[341797169] 'agreement among raft nodes before linearized reading' (duration: 180.940481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.435581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.88925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-07T04:08:55.435789Z","caller":"traceutil/trace.go:171","msg":"trace[1417975359] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1849; }","duration":"142.125857ms","start":"2026-02-07T04:08:55.293652Z","end":"2026-02-07T04:08:55.435778Z","steps":["trace[1417975359] 'range keys from in-memory index tree' (duration: 141.696245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:55.436139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.426121ms","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":"warn","ts":"2026-02-07T04:08:55.436984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.300145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T04:08:55.437055Z","caller":"traceutil/trace.go:171","msg":"trace[192744873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1849; }","duration":"129.385597ms","start":"2026-02-07T04:08:55.307663Z","end":"2026-02-07T04:08:55.437048Z","steps":["trace[192744873] 'range keys from in-memory index tree' (duration: 129.222483ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:55.437676Z","caller":"traceutil/trace.go:171","msg":"trace[1279875630] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1849; }","duration":"128.528054ms","start":"2026-02-07T04:08:55.307671Z","end":"2026-02-07T04:08:55.436199Z","steps":["trace[1279875630] 'range keys from in-memory index tree' (duration: 128.298498ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:56.350299Z","caller":"traceutil/trace.go:171","msg":"trace[437103870] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"103.21915ms","start":"2026-02-07T04:08:56.247062Z","end":"2026-02-07T04:08:56.350281Z","steps":["trace[437103870] 'process raft request' (duration: 103.057615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:57.765443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837749587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:08:58.26621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837749587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:08:58.766681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057915837749587,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T04:08:58.895975Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.674977305s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T04:08:58.896208Z","caller":"traceutil/trace.go:171","msg":"trace[1248856180] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"1.631464502s","start":"2026-02-07T04:08:57.264729Z","end":"2026-02-07T04:08:58.896193Z","steps":["trace[1248856180] 'read index received' (duration: 1.631313868s)","trace[1248856180] 'applied index is now lower than readState.Index' (duration: 150.054µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:58.896282Z","caller":"traceutil/trace.go:171","msg":"trace[506764908] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"1.675356095s","start":"2026-02-07T04:08:57.220918Z","end":"2026-02-07T04:08:58.896274Z","steps":["trace[506764908] 'process raft request' (duration: 1.6751666s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.896353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.220905Z","time spent":"1.675397546s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T04:08:58.896552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.631827602s","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-07T04:08:58.896579Z","caller":"traceutil/trace.go:171","msg":"trace[1370904391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1856; }","duration":"1.631878833s","start":"2026-02-07T04:08:57.264693Z","end":"2026-02-07T04:08:58.896572Z","steps":["trace[1370904391] 'agreement among raft nodes before linearized reading' (duration: 1.631801221s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.896598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.264679Z","time spent":"1.631915054s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T04:08:58.896679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42642068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-07T04:08:58.896702Z","caller":"traceutil/trace.go:171","msg":"trace[812013629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1856; }","duration":"1.426483402s","start":"2026-02-07T04:08:57.470212Z","end":"2026-02-07T04:08:58.896696Z","steps":["trace[812013629] 'agreement among raft nodes before linearized reading' (duration: 1.42643417s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.896719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.470191Z","time spent":"1.426524633s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T04:08:58.896852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.70884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-07T04:08:58.896875Z","caller":"traceutil/trace.go:171","msg":"trace[1221323547] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1856; }","duration":"395.766952ms","start":"2026-02-07T04:08:58.501102Z","end":"2026-02-07T04:08:58.896869Z","steps":["trace[1221323547] 'agreement among raft nodes before linearized reading' (duration: 395.71861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.896891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:58.501083Z","time spent":"395.804502ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:08:58.896955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.756784ms","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-07T04:08:58.896975Z","caller":"traceutil/trace.go:171","msg":"trace[1230757513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1856; }","duration":"541.806195ms","start":"2026-02-07T04:08:58.355164Z","end":"2026-02-07T04:08:58.89697Z","steps":["trace[1230757513] 'agreement among raft nodes before linearized reading' (duration: 541.758404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.896992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:58.355148Z","time spent":"541.840437ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T04:08:58.89706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.56823ms","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:526"} {"level":"info","ts":"2026-02-07T04:08:58.897079Z","caller":"traceutil/trace.go:171","msg":"trace[453239434] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1856; }","duration":"587.622281ms","start":"2026-02-07T04:08:58.309452Z","end":"2026-02-07T04:08:58.897074Z","steps":["trace[453239434] 'agreement among raft nodes before linearized reading' (duration: 587.57859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.897094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:58.309431Z","time spent":"587.659893ms","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T04:08:58.897139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1017984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:58.897157Z","caller":"traceutil/trace.go:171","msg":"trace[1856787652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"1.1018465s","start":"2026-02-07T04:08:57.795306Z","end":"2026-02-07T04:08:58.897153Z","steps":["trace[1856787652] 'agreement among raft nodes before linearized reading' (duration: 1.10182313s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.897171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.795287Z","time spent":"1.101880802s","remote":"127.0.0.1:36202","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-07T04:08:58.90023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380317395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-07T04:08:58.90026Z","caller":"traceutil/trace.go:171","msg":"trace[1886772273] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1856; }","duration":"1.380349426s","start":"2026-02-07T04:08:57.519904Z","end":"2026-02-07T04:08:58.900253Z","steps":["trace[1886772273] 'agreement among raft nodes before linearized reading' (duration: 1.380274424s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.900281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.519887Z","time spent":"1.380389068s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-07T04:08:58.900483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381531799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-07T04:08:58.90051Z","caller":"traceutil/trace.go:171","msg":"trace[1222886226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1856; }","duration":"1.38158699s","start":"2026-02-07T04:08:57.518917Z","end":"2026-02-07T04:08:58.900504Z","steps":["trace[1222886226] 'agreement among raft nodes before linearized reading' (duration: 1.381519608s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T04:08:58.900528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T04:08:57.518903Z","time spent":"1.381620572s","remote":"127.0.0.1:36462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-07T04:08:59.044379Z","caller":"traceutil/trace.go:171","msg":"trace[345087064] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"138.883508ms","start":"2026-02-07T04:08:58.905469Z","end":"2026-02-07T04:08:59.044352Z","steps":["trace[345087064] 'read index received' (duration: 118.14265ms)","trace[345087064] 'applied index is now lower than readState.Index' (duration: 20.739758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T04:08:59.044528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.033712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T04:08:59.044626Z","caller":"traceutil/trace.go:171","msg":"trace[1578611230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"139.156095ms","start":"2026-02-07T04:08:58.905453Z","end":"2026-02-07T04:08:59.044609Z","steps":["trace[1578611230] 'agreement among raft nodes before linearized reading' (duration: 138.998961ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:59.045027Z","caller":"traceutil/trace.go:171","msg":"trace[962059974] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"139.754262ms","start":"2026-02-07T04:08:58.905247Z","end":"2026-02-07T04:08:59.045002Z","steps":["trace[962059974] 'process raft request' (duration: 118.354025ms)","trace[962059974] 'compare' (duration: 20.649936ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T04:08:59.076842Z","caller":"traceutil/trace.go:171","msg":"trace[1844925966] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"171.258165ms","start":"2026-02-07T04:08:58.90557Z","end":"2026-02-07T04:08:59.076828Z","steps":["trace[1844925966] 'process raft request' (duration: 171.08446ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:59.077097Z","caller":"traceutil/trace.go:171","msg":"trace[1713151899] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"171.446579ms","start":"2026-02-07T04:08:58.905628Z","end":"2026-02-07T04:08:59.077074Z","steps":["trace[1713151899] 'process raft request' (duration: 171.128761ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:59.077306Z","caller":"traceutil/trace.go:171","msg":"trace[787953849] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"168.233521ms","start":"2026-02-07T04:08:58.909061Z","end":"2026-02-07T04:08:59.077294Z","steps":["trace[787953849] 'process raft request' (duration: 167.719947ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:59.077575Z","caller":"traceutil/trace.go:171","msg":"trace[420672257] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"168.459678ms","start":"2026-02-07T04:08:58.909108Z","end":"2026-02-07T04:08:59.077567Z","steps":["trace[420672257] 'process raft request' (duration: 167.702277ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:59.077721Z","caller":"traceutil/trace.go:171","msg":"trace[2130195153] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"168.54409ms","start":"2026-02-07T04:08:58.909139Z","end":"2026-02-07T04:08:59.077683Z","steps":["trace[2130195153] 'process raft request' (duration: 167.692277ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:59.078206Z","caller":"traceutil/trace.go:171","msg":"trace[979811525] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"169.025143ms","start":"2026-02-07T04:08:58.909168Z","end":"2026-02-07T04:08:59.078193Z","steps":["trace[979811525] 'process raft request' (duration: 167.682216ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T04:08:59.078237Z","caller":"traceutil/trace.go:171","msg":"trace[1263414908] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"168.640573ms","start":"2026-02-07T04:08:58.90959Z","end":"2026-02-07T04:08:59.07823Z","steps":["trace[1263414908] 'process raft request' (duration: 167.355158ms)"],"step_count":1}