{"level":"warn","ts":"2026-02-05T17:40:48.608082Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:40:48.615925Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-05T17:40:48.617015Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:40:48.617033Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-05T17:40:48.617652Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-05T17:40:48.621668Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-02-05T17:40:48.624485Z","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://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-05T17:40:48.630976Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.478673ms"} {"level":"info","ts":"2026-02-05T17:40:48.655408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-02-05T17:40:48.655769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:40:48.655797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-02-05T17:40:48.655848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:40:48.655903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-02-05T17:40:48.655957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-02-05T17:40:48.671547Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:40:48.674554Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:40:48.677975Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-05T17:40:48.683598Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:40:48.686457Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:40:48.686703Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T17:40:48.686804Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T17:40:48.686855Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T17:40:48.687126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-02-05T17:40:48.687269Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-05T17:40:48.710424Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-05T17:40:48.710667Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:40:48.710741Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:40:48.710813Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-05T17:40:48.710884Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-05T17:40:49.057873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:40:49.057918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:40:49.057938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-02-05T17:40:49.057954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:40:49.057964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-05T17:40:49.057972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-02-05T17:40:49.05798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-05T17:40:49.066911Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:40:49.067139Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:40:49.068671Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:40:49.068964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:40:49.069039Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:40:49.069102Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:40:49.069165Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:40:49.069308Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:40:49.069373Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:40:49.070825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-02-05T17:40:49.092188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:41:00.902596Z","caller":"traceutil/trace.go:171","msg":"trace[1496616394] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"106.583391ms","start":"2026-02-05T17:41:00.795936Z","end":"2026-02-05T17:41:00.90252Z","steps":["trace[1496616394] 'process raft request' (duration: 106.386752ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:41:00.904714Z","caller":"traceutil/trace.go:171","msg":"trace[1256960535] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:408; }","duration":"105.014434ms","start":"2026-02-05T17:41:00.799655Z","end":"2026-02-05T17:41:00.904669Z","steps":["trace[1256960535] 'read index received' (duration: 105.008213ms)","trace[1256960535] 'applied index is now lower than readState.Index' (duration: 5.071µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:41:00.905032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.162491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:41:00.905195Z","caller":"traceutil/trace.go:171","msg":"trace[638174008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:397; }","duration":"105.483004ms","start":"2026-02-05T17:41:00.799633Z","end":"2026-02-05T17:41:00.905116Z","steps":["trace[638174008] 'agreement among raft nodes before linearized reading' (duration: 105.123418ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:41:07.296171Z","caller":"traceutil/trace.go:171","msg":"trace[1427245520] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"353.49185ms","start":"2026-02-05T17:41:06.942644Z","end":"2026-02-05T17:41:07.296136Z","steps":["trace[1427245520] 'process raft request' (duration: 353.296701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:07.296943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:06.942635Z","time spent":"353.634535ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:41:07.314865Z","caller":"traceutil/trace.go:171","msg":"trace[309420951] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"372.081011ms","start":"2026-02-05T17:41:06.942762Z","end":"2026-02-05T17:41:07.314843Z","steps":["trace[309420951] 'process raft request' (duration: 371.783588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:07.31501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:06.942758Z","time spent":"372.174725ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:41:07.494255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.390098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T17:41:07.494422Z","caller":"traceutil/trace.go:171","msg":"trace[412010136] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"389.70525ms","start":"2026-02-05T17:41:07.104662Z","end":"2026-02-05T17:41:07.494367Z","steps":["trace[412010136] 'read index received' (duration: 197.962629ms)","trace[412010136] 'applied index is now lower than readState.Index' (duration: 191.74069ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:41:07.494873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.19388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T17:41:07.49491Z","caller":"traceutil/trace.go:171","msg":"trace[136173877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:450; }","duration":"390.295675ms","start":"2026-02-05T17:41:07.104602Z","end":"2026-02-05T17:41:07.494897Z","steps":["trace[136173877] 'agreement among raft nodes before linearized reading' (duration: 390.207812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:07.494946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:07.104559Z","time spent":"390.378329ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:41:21.591825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.077581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:41:21.591878Z","caller":"traceutil/trace.go:171","msg":"trace[1074002240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"228.167353ms","start":"2026-02-05T17:41:21.3637Z","end":"2026-02-05T17:41:21.591867Z","steps":["trace[1074002240] 'range keys from in-memory index tree' (duration: 228.015068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:21.591992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.223657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:41:21.592053Z","caller":"traceutil/trace.go:171","msg":"trace[1498704236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"225.30649ms","start":"2026-02-05T17:41:21.366735Z","end":"2026-02-05T17:41:21.592041Z","steps":["trace[1498704236] 'range keys from in-memory index tree' (duration: 225.176015ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:41:22.666584Z","caller":"traceutil/trace.go:171","msg":"trace[840355514] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"268.388503ms","start":"2026-02-05T17:41:22.398181Z","end":"2026-02-05T17:41:22.66657Z","steps":["trace[840355514] 'read index received' (duration: 268.26986ms)","trace[840355514] 'applied index is now lower than readState.Index' (duration: 118.153µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:41:22.666608Z","caller":"traceutil/trace.go:171","msg":"trace[1405860289] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"404.217978ms","start":"2026-02-05T17:41:22.262372Z","end":"2026-02-05T17:41:22.66659Z","steps":["trace[1405860289] 'process raft request' (duration: 404.115365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:22.666666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.477136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:41:22.666687Z","caller":"traceutil/trace.go:171","msg":"trace[1783364920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"268.529528ms","start":"2026-02-05T17:41:22.398149Z","end":"2026-02-05T17:41:22.666679Z","steps":["trace[1783364920] 'agreement among raft nodes before linearized reading' (duration: 268.474816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:22.666701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:22.262356Z","time spent":"404.296551ms","remote":"127.0.0.1:46674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:41:24.809467Z","caller":"traceutil/trace.go:171","msg":"trace[1221033133] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"127.155732ms","start":"2026-02-05T17:41:24.682286Z","end":"2026-02-05T17:41:24.809442Z","steps":["trace[1221033133] 'process raft request' (duration: 127.017418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:27.751252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.64184ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T17:41:27.909401Z","caller":"traceutil/trace.go:171","msg":"trace[1315517143] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:548; }","duration":"506.810761ms","start":"2026-02-05T17:41:27.402572Z","end":"2026-02-05T17:41:27.909383Z","steps":["trace[1315517143] 'read index received' (duration: 240.254397ms)","trace[1315517143] 'applied index is now lower than readState.Index' (duration: 266.555644ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:41:27.90948Z","caller":"traceutil/trace.go:171","msg":"trace[1169396433] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"672.818649ms","start":"2026-02-05T17:41:27.236656Z","end":"2026-02-05T17:41:27.909474Z","steps":["trace[1169396433] 'process raft request' (duration: 672.616993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:27.909558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:27.236637Z","time spent":"672.862571ms","remote":"127.0.0.1:46842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:41:27.909719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.157972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:41:27.909739Z","caller":"traceutil/trace.go:171","msg":"trace[372732288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"507.212684ms","start":"2026-02-05T17:41:27.402521Z","end":"2026-02-05T17:41:27.909733Z","steps":["trace[372732288] 'agreement among raft nodes before linearized reading' (duration: 507.178082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:27.909756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:27.402503Z","time spent":"507.250075ms","remote":"127.0.0.1:46660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:41:27.909877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.681642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:41:27.909894Z","caller":"traceutil/trace.go:171","msg":"trace[477922877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"287.734294ms","start":"2026-02-05T17:41:27.622155Z","end":"2026-02-05T17:41:27.909889Z","steps":["trace[477922877] 'agreement among raft nodes before linearized reading' (duration: 287.701723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:27.910008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.585393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:41:27.910026Z","caller":"traceutil/trace.go:171","msg":"trace[1130181119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"281.614455ms","start":"2026-02-05T17:41:27.628407Z","end":"2026-02-05T17:41:27.910021Z","steps":["trace[1130181119] 'agreement among raft nodes before linearized reading' (duration: 281.584424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:27.910072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.114646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:41:27.919364Z","caller":"traceutil/trace.go:171","msg":"trace[1437935507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"293.462301ms","start":"2026-02-05T17:41:27.62589Z","end":"2026-02-05T17:41:27.919353Z","steps":["trace[1437935507] 'agreement among raft nodes before linearized reading' (duration: 284.125217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:27.910273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.358949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T17:41:27.919441Z","caller":"traceutil/trace.go:171","msg":"trace[251923897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"291.546618ms","start":"2026-02-05T17:41:27.62789Z","end":"2026-02-05T17:41:27.919436Z","steps":["trace[251923897] 'agreement among raft nodes before linearized reading' (duration: 282.361288ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:41:44.299336Z","caller":"traceutil/trace.go:171","msg":"trace[620927528] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"253.625432ms","start":"2026-02-05T17:41:44.045694Z","end":"2026-02-05T17:41:44.299319Z","steps":["trace[620927528] 'read index received' (duration: 253.486548ms)","trace[620927528] 'applied index is now lower than readState.Index' (duration: 138.274µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:41:44.299585Z","caller":"traceutil/trace.go:171","msg":"trace[830962012] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"282.98996ms","start":"2026-02-05T17:41:44.016587Z","end":"2026-02-05T17:41:44.299577Z","steps":["trace[830962012] 'process raft request' (duration: 282.637328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:44.299774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.078828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T17:41:44.299796Z","caller":"traceutil/trace.go:171","msg":"trace[769574194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"254.178571ms","start":"2026-02-05T17:41:44.04561Z","end":"2026-02-05T17:41:44.299789Z","steps":["trace[769574194] 'agreement among raft nodes before linearized reading' (duration: 254.090118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:44.299905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.549639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T17:41:44.300297Z","caller":"traceutil/trace.go:171","msg":"trace[1383894028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"241.591371ms","start":"2026-02-05T17:41:44.058325Z","end":"2026-02-05T17:41:44.299916Z","steps":["trace[1383894028] 'agreement among raft nodes before linearized reading' (duration: 241.543739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:44.300655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.783412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T17:41:44.300699Z","caller":"traceutil/trace.go:171","msg":"trace[67296246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"200.884184ms","start":"2026-02-05T17:41:44.099805Z","end":"2026-02-05T17:41:44.300689Z","steps":["trace[67296246] 'agreement among raft nodes before linearized reading' (duration: 200.801951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:44.300942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.854706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T17:41:44.300964Z","caller":"traceutil/trace.go:171","msg":"trace[914978362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"201.900608ms","start":"2026-02-05T17:41:44.099058Z","end":"2026-02-05T17:41:44.300959Z","steps":["trace[914978362] 'agreement among raft nodes before linearized reading' (duration: 201.787904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:44.900088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119558878008781,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:41:45.40074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119558878008781,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:41:45.682872Z","caller":"traceutil/trace.go:171","msg":"trace[1546828712] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"1.376635037s","start":"2026-02-05T17:41:44.30621Z","end":"2026-02-05T17:41:45.682845Z","steps":["trace[1546828712] 'process raft request' (duration: 1.376484172s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:45.683244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:44.306197Z","time spent":"1.376753411s","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:41:45.730952Z","caller":"traceutil/trace.go:171","msg":"trace[350980141] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.424639851s","start":"2026-02-05T17:41:44.306292Z","end":"2026-02-05T17:41:45.730932Z","steps":["trace[350980141] 'process raft request' (duration: 1.42123953s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:45.731062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:44.306287Z","time spent":"1.424716693s","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:41:45.732471Z","caller":"traceutil/trace.go:171","msg":"trace[575257869] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:735; }","duration":"1.33282106s","start":"2026-02-05T17:41:44.39964Z","end":"2026-02-05T17:41:45.732461Z","steps":["trace[575257869] 'read index received' (duration: 1.28309886s)","trace[575257869] 'applied index is now lower than readState.Index' (duration: 49.72152ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:41:45.732764Z","caller":"traceutil/trace.go:171","msg":"trace[1196444426] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"1.424690103s","start":"2026-02-05T17:41:44.308062Z","end":"2026-02-05T17:41:45.732752Z","steps":["trace[1196444426] 'process raft request' (duration: 1.42429787s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:45.732982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:44.308044Z","time spent":"1.424754165s","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:41:45.733372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33372903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:41:45.733424Z","caller":"traceutil/trace.go:171","msg":"trace[1961746744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"1.333817613s","start":"2026-02-05T17:41:44.399596Z","end":"2026-02-05T17:41:45.733414Z","steps":["trace[1961746744] 'agreement among raft nodes before linearized reading' (duration: 1.332904503s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:45.733454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:44.399586Z","time spent":"1.333862664s","remote":"127.0.0.1:46646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:41:45.734254Z","caller":"traceutil/trace.go:171","msg":"trace[1731605824] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"1.427900688s","start":"2026-02-05T17:41:44.306342Z","end":"2026-02-05T17:41:45.734243Z","steps":["trace[1731605824] 'process raft request' (duration: 1.423523535s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:45.734412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:44.306337Z","time spent":"1.427947969s","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:41:45.735102Z","caller":"traceutil/trace.go:171","msg":"trace[128388380] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"450.339301ms","start":"2026-02-05T17:41:45.28475Z","end":"2026-02-05T17:41:45.73509Z","steps":["trace[128388380] 'process raft request' (duration: 447.683755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:45.735166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:45.284715Z","time spent":"450.420554ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:41:45.7351Z","caller":"traceutil/trace.go:171","msg":"trace[1809608419] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"620.391991ms","start":"2026-02-05T17:41:45.114535Z","end":"2026-02-05T17:41:45.734927Z","steps":["trace[1809608419] 'process raft request' (duration: 617.864219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:41:45.735393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:41:45.114517Z","time spent":"620.799885ms","remote":"127.0.0.1:46734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:42:01.843357Z","caller":"traceutil/trace.go:171","msg":"trace[623566605] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"101.872957ms","start":"2026-02-05T17:42:01.741457Z","end":"2026-02-05T17:42:01.84333Z","steps":["trace[623566605] 'process raft request' (duration: 101.720592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:06.370423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119558878008954,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T17:42:06.495261Z","caller":"traceutil/trace.go:171","msg":"trace[1556383935] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"628.024775ms","start":"2026-02-05T17:42:05.867211Z","end":"2026-02-05T17:42:06.495236Z","steps":["trace[1556383935] 'process raft request' (duration: 627.894491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:06.495414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:05.867194Z","time spent":"628.132878ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:06.504715Z","caller":"traceutil/trace.go:171","msg":"trace[1449510240] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:802; }","duration":"634.572242ms","start":"2026-02-05T17:42:05.87013Z","end":"2026-02-05T17:42:06.504702Z","steps":["trace[1449510240] 'read index received' (duration: 625.015969ms)","trace[1449510240] 'applied index is now lower than readState.Index' (duration: 9.555672ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:06.504868Z","caller":"traceutil/trace.go:171","msg":"trace[469914902] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"439.473551ms","start":"2026-02-05T17:42:06.065384Z","end":"2026-02-05T17:42:06.504857Z","steps":["trace[469914902] 'process raft request' (duration: 439.273045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:06.50491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:06.065362Z","time spent":"439.521382ms","remote":"127.0.0.1:46924","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-05T17:42:06.505066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.932993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:42:06.505089Z","caller":"traceutil/trace.go:171","msg":"trace[984539655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"634.964714ms","start":"2026-02-05T17:42:05.870117Z","end":"2026-02-05T17:42:06.505081Z","steps":["trace[984539655] 'agreement among raft nodes before linearized reading' (duration: 634.873521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:06.505107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:05.870111Z","time spent":"634.992255ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T17:42:06.505213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.877298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:42:06.505231Z","caller":"traceutil/trace.go:171","msg":"trace[1973736522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"626.911999ms","start":"2026-02-05T17:42:05.878314Z","end":"2026-02-05T17:42:06.505226Z","steps":["trace[1973736522] 'agreement among raft nodes before linearized reading' (duration: 626.864858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:06.505245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:05.878303Z","time spent":"626.9392ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:42:06.50543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.799524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:42:06.505447Z","caller":"traceutil/trace.go:171","msg":"trace[1530664572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"101.854826ms","start":"2026-02-05T17:42:06.403587Z","end":"2026-02-05T17:42:06.505442Z","steps":["trace[1530664572] 'agreement among raft nodes before linearized reading' (duration: 101.828575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:06.505576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.205738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:42:06.505604Z","caller":"traceutil/trace.go:171","msg":"trace[343708363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"627.247229ms","start":"2026-02-05T17:42:05.878351Z","end":"2026-02-05T17:42:06.505599Z","steps":["trace[343708363] 'agreement among raft nodes before linearized reading' (duration: 627.190587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:06.50562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:05.878346Z","time spent":"627.27027ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T17:42:10.740248Z","caller":"traceutil/trace.go:171","msg":"trace[694422343] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"228.357164ms","start":"2026-02-05T17:42:10.511875Z","end":"2026-02-05T17:42:10.740232Z","steps":["trace[694422343] 'process raft request' (duration: 228.024114ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:10.742413Z","caller":"traceutil/trace.go:171","msg":"trace[78908065] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"199.561142ms","start":"2026-02-05T17:42:10.540475Z","end":"2026-02-05T17:42:10.740036Z","steps":["trace[78908065] 'read index received' (duration: 199.317544ms)","trace[78908065] 'applied index is now lower than readState.Index' (duration: 242.458µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:10.74275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.264388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:42:10.742851Z","caller":"traceutil/trace.go:171","msg":"trace[1913714881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"202.418673ms","start":"2026-02-05T17:42:10.540417Z","end":"2026-02-05T17:42:10.742835Z","steps":["trace[1913714881] 'agreement among raft nodes before linearized reading' (duration: 202.208626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:10.743079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.612103ms","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-05T17:42:10.743171Z","caller":"traceutil/trace.go:171","msg":"trace[1773622922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"145.732487ms","start":"2026-02-05T17:42:10.597428Z","end":"2026-02-05T17:42:10.74316Z","steps":["trace[1773622922] 'agreement among raft nodes before linearized reading' (duration: 145.588062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:10.743562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.105314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:42:10.743659Z","caller":"traceutil/trace.go:171","msg":"trace[2011524965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"143.251008ms","start":"2026-02-05T17:42:10.600397Z","end":"2026-02-05T17:42:10.743648Z","steps":["trace[2011524965] 'agreement among raft nodes before linearized reading' (duration: 143.029431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:10.744004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.454708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:42:10.744053Z","caller":"traceutil/trace.go:171","msg":"trace[2017799970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"145.552351ms","start":"2026-02-05T17:42:10.598489Z","end":"2026-02-05T17:42:10.744041Z","steps":["trace[2017799970] 'agreement among raft nodes before linearized reading' (duration: 145.435057ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:10.877207Z","caller":"traceutil/trace.go:171","msg":"trace[2123201552] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"128.133949ms","start":"2026-02-05T17:42:10.749041Z","end":"2026-02-05T17:42:10.877175Z","steps":["trace[2123201552] 'process raft request' (duration: 95.32319ms)","trace[2123201552] 'compare' (duration: 32.702036ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:10.932737Z","caller":"traceutil/trace.go:171","msg":"trace[1620114864] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"183.568365ms","start":"2026-02-05T17:42:10.749139Z","end":"2026-02-05T17:42:10.932707Z","steps":["trace[1620114864] 'process raft request' (duration: 183.066479ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:10.936057Z","caller":"traceutil/trace.go:171","msg":"trace[1503457955] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"186.154297ms","start":"2026-02-05T17:42:10.749884Z","end":"2026-02-05T17:42:10.936038Z","steps":["trace[1503457955] 'process raft request' (duration: 182.46947ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:10.937059Z","caller":"traceutil/trace.go:171","msg":"trace[1006243191] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"186.077415ms","start":"2026-02-05T17:42:10.750969Z","end":"2026-02-05T17:42:10.937047Z","steps":["trace[1006243191] 'process raft request' (duration: 185.867898ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:25.104451Z","caller":"traceutil/trace.go:171","msg":"trace[1329534734] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"163.591222ms","start":"2026-02-05T17:42:24.940831Z","end":"2026-02-05T17:42:25.104422Z","steps":["trace[1329534734] 'read index received' (duration: 163.345134ms)","trace[1329534734] 'applied index is now lower than readState.Index' (duration: 245.348µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:25.104574Z","caller":"traceutil/trace.go:171","msg":"trace[1115497233] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"256.178206ms","start":"2026-02-05T17:42:24.848387Z","end":"2026-02-05T17:42:25.104566Z","steps":["trace[1115497233] 'process raft request' (duration: 255.862836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:25.104845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.010755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:42:25.104908Z","caller":"traceutil/trace.go:171","msg":"trace[174641039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:881; }","duration":"164.123969ms","start":"2026-02-05T17:42:24.940768Z","end":"2026-02-05T17:42:25.104892Z","steps":["trace[174641039] 'agreement among raft nodes before linearized reading' (duration: 164.032856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:25.580079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.012662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:42:25.580169Z","caller":"traceutil/trace.go:171","msg":"trace[194622461] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"471.656562ms","start":"2026-02-05T17:42:25.108498Z","end":"2026-02-05T17:42:25.580155Z","steps":["trace[194622461] 'process raft request' (duration: 220.505066ms)","trace[194622461] 'compare' (duration: 250.815075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:25.580204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:25.108479Z","time spent":"471.710283ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:25.620189Z","caller":"traceutil/trace.go:171","msg":"trace[421910779] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:913; }","duration":"218.172541ms","start":"2026-02-05T17:42:25.401995Z","end":"2026-02-05T17:42:25.620167Z","steps":["trace[421910779] 'read index received' (duration: 217.989666ms)","trace[421910779] 'applied index is now lower than readState.Index' (duration: 182.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:25.620245Z","caller":"traceutil/trace.go:171","msg":"trace[288879692] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"511.526855ms","start":"2026-02-05T17:42:25.108681Z","end":"2026-02-05T17:42:25.620208Z","steps":["trace[288879692] 'process raft request' (duration: 511.355179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:25.620317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.315896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:42:25.620338Z","caller":"traceutil/trace.go:171","msg":"trace[1344653690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"218.397849ms","start":"2026-02-05T17:42:25.401934Z","end":"2026-02-05T17:42:25.620332Z","steps":["trace[1344653690] 'agreement among raft nodes before linearized reading' (duration: 218.307126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:25.620407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:25.108676Z","time spent":"511.651118ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:25.62049Z","caller":"traceutil/trace.go:171","msg":"trace[701357549] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"510.436059ms","start":"2026-02-05T17:42:25.110048Z","end":"2026-02-05T17:42:25.620484Z","steps":["trace[701357549] 'process raft request' (duration: 510.080618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:25.620549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:25.110041Z","time spent":"510.462791ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:25.6206Z","caller":"traceutil/trace.go:171","msg":"trace[687233607] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"508.707155ms","start":"2026-02-05T17:42:25.111889Z","end":"2026-02-05T17:42:25.620596Z","steps":["trace[687233607] 'process raft request' (duration: 508.258251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:25.620633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:25.111869Z","time spent":"508.750337ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:37.975277Z","caller":"traceutil/trace.go:171","msg":"trace[710814674] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"290.368979ms","start":"2026-02-05T17:42:37.68489Z","end":"2026-02-05T17:42:37.975259Z","steps":["trace[710814674] 'process raft request' (duration: 258.291833ms)","trace[710814674] 'compare' (duration: 31.968952ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:37.975613Z","caller":"traceutil/trace.go:171","msg":"trace[2110097256] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:950; }","duration":"287.123556ms","start":"2026-02-05T17:42:37.688483Z","end":"2026-02-05T17:42:37.975607Z","steps":["trace[2110097256] 'read index received' (duration: 254.664657ms)","trace[2110097256] 'applied index is now lower than readState.Index' (duration: 32.458379ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:37.975693Z","caller":"traceutil/trace.go:171","msg":"trace[385509805] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"295.584393ms","start":"2026-02-05T17:42:37.680104Z","end":"2026-02-05T17:42:37.975688Z","steps":["trace[385509805] 'process raft request' (duration: 295.4702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:37.975814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.319702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:42:37.97583Z","caller":"traceutil/trace.go:171","msg":"trace[1067468132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"287.361404ms","start":"2026-02-05T17:42:37.688464Z","end":"2026-02-05T17:42:37.975825Z","steps":["trace[1067468132] 'agreement among raft nodes before linearized reading' (duration: 287.290381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:37.975894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.856164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:42:37.975908Z","caller":"traceutil/trace.go:171","msg":"trace[546300370] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:918; }","duration":"284.879644ms","start":"2026-02-05T17:42:37.691023Z","end":"2026-02-05T17:42:37.975903Z","steps":["trace[546300370] 'agreement among raft nodes before linearized reading' (duration: 284.858663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:37.976003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.91919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:42:37.976016Z","caller":"traceutil/trace.go:171","msg":"trace[917903331] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:918; }","duration":"197.956592ms","start":"2026-02-05T17:42:37.778056Z","end":"2026-02-05T17:42:37.976012Z","steps":["trace[917903331] 'agreement among raft nodes before linearized reading' (duration: 197.931151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:40.357627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.148601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:42:40.357694Z","caller":"traceutil/trace.go:171","msg":"trace[1479029857] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"360.432048ms","start":"2026-02-05T17:42:39.997252Z","end":"2026-02-05T17:42:40.357684Z","steps":["trace[1479029857] 'read index received' (duration: 12.812326ms)","trace[1479029857] 'applied index is now lower than readState.Index' (duration: 347.618862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:40.35777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.51892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:42:40.357782Z","caller":"traceutil/trace.go:171","msg":"trace[1928197163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"360.558952ms","start":"2026-02-05T17:42:39.997218Z","end":"2026-02-05T17:42:40.357777Z","steps":["trace[1928197163] 'agreement among raft nodes before linearized reading' (duration: 360.489459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:40.3578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:39.997206Z","time spent":"360.587263ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-05T17:42:40.357831Z","caller":"traceutil/trace.go:171","msg":"trace[525873445] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"374.042608ms","start":"2026-02-05T17:42:39.983762Z","end":"2026-02-05T17:42:40.357805Z","steps":["trace[525873445] 'process raft request' (duration: 220.66905ms)","trace[525873445] 'compare' (duration: 153.015507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:40.357972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:39.983739Z","time spent":"374.147682ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:42:40.76073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.714085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:42:40.760817Z","caller":"traceutil/trace.go:171","msg":"trace[1186381225] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"353.149317ms","start":"2026-02-05T17:42:40.407654Z","end":"2026-02-05T17:42:40.760803Z","steps":["trace[1186381225] 'read index received' (duration: 160.299678ms)","trace[1186381225] 'applied index is now lower than readState.Index' (duration: 192.848559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:40.760909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.25231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:42:40.760942Z","caller":"traceutil/trace.go:171","msg":"trace[1464631914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"353.310562ms","start":"2026-02-05T17:42:40.407621Z","end":"2026-02-05T17:42:40.760931Z","steps":["trace[1464631914] 'agreement among raft nodes before linearized reading' (duration: 353.239039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:40.760977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:40.407607Z","time spent":"353.362564ms","remote":"127.0.0.1:46646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:42:40.761238Z","caller":"traceutil/trace.go:171","msg":"trace[403455203] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"393.818226ms","start":"2026-02-05T17:42:40.367406Z","end":"2026-02-05T17:42:40.761224Z","steps":["trace[403455203] 'process raft request' (duration: 200.511633ms)","trace[403455203] 'compare' (duration: 192.242559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:40.761302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:40.36739Z","time spent":"393.880117ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:42:41.568675Z","caller":"traceutil/trace.go:171","msg":"trace[1661234895] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"368.316887ms","start":"2026-02-05T17:42:41.200345Z","end":"2026-02-05T17:42:41.568662Z","steps":["trace[1661234895] 'process raft request' (duration: 367.987307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:41.568761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:42:41.200337Z","time spent":"368.375029ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:42:42.65728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.443123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:42:42.657352Z","caller":"traceutil/trace.go:171","msg":"trace[385354186] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"258.076555ms","start":"2026-02-05T17:42:42.399263Z","end":"2026-02-05T17:42:42.65734Z","steps":["trace[385354186] 'read index received' (duration: 133.511069ms)","trace[385354186] 'applied index is now lower than readState.Index' (duration: 124.564606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:42:42.657392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.126967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:42:42.657408Z","caller":"traceutil/trace.go:171","msg":"trace[730712061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"258.156198ms","start":"2026-02-05T17:42:42.399246Z","end":"2026-02-05T17:42:42.657402Z","steps":["trace[730712061] 'agreement among raft nodes before linearized reading' (duration: 258.127457ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:42.657557Z","caller":"traceutil/trace.go:171","msg":"trace[873890497] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"286.915859ms","start":"2026-02-05T17:42:42.370632Z","end":"2026-02-05T17:42:42.657548Z","steps":["trace[873890497] 'process raft request' (duration: 162.133856ms)","trace[873890497] 'compare' (duration: 124.181034ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:42:42.657603Z","caller":"traceutil/trace.go:171","msg":"trace[641135081] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"147.484702ms","start":"2026-02-05T17:42:42.510095Z","end":"2026-02-05T17:42:42.657579Z","steps":["trace[641135081] 'process raft request' (duration: 147.398839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:42.937294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.774301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T17:42:42.937348Z","caller":"traceutil/trace.go:171","msg":"trace[2143465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"147.860654ms","start":"2026-02-05T17:42:42.789474Z","end":"2026-02-05T17:42:42.937335Z","steps":["trace[2143465] 'range keys from in-memory index tree' (duration: 147.635927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:56.434828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.046046ms","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":"warn","ts":"2026-02-05T17:42:56.44023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.433982ms","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:533"} {"level":"info","ts":"2026-02-05T17:42:56.440335Z","caller":"traceutil/trace.go:171","msg":"trace[1584686785] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1032; }","duration":"136.565935ms","start":"2026-02-05T17:42:56.303751Z","end":"2026-02-05T17:42:56.440317Z","steps":["trace[1584686785] 'range keys from in-memory index tree' (duration: 136.304327ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:42:56.454771Z","caller":"traceutil/trace.go:171","msg":"trace[556249607] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1032; }","duration":"176.187721ms","start":"2026-02-05T17:42:56.258713Z","end":"2026-02-05T17:42:56.4349Z","steps":["trace[556249607] 'count revisions from in-memory index tree' (duration: 175.85853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:42:56.995596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.091236ms","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-05T17:42:56.995673Z","caller":"traceutil/trace.go:171","msg":"trace[1542144053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"217.21401ms","start":"2026-02-05T17:42:56.778442Z","end":"2026-02-05T17:42:56.995656Z","steps":["trace[1542144053] 'range keys from in-memory index tree' (duration: 216.242339ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:11.183333Z","caller":"traceutil/trace.go:171","msg":"trace[1192520186] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"119.172518ms","start":"2026-02-05T17:43:11.064128Z","end":"2026-02-05T17:43:11.183301Z","steps":["trace[1192520186] 'process raft request' (duration: 119.017893ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:43:11.199442Z","caller":"traceutil/trace.go:171","msg":"trace[1138083707] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"133.17122ms","start":"2026-02-05T17:43:11.066261Z","end":"2026-02-05T17:43:11.199432Z","steps":["trace[1138083707] 'process raft request' (duration: 133.126289ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:50.430079Z","caller":"traceutil/trace.go:171","msg":"trace[1894708311] linearizableReadLoop","detail":"{readStateIndex:1831; appliedIndex:1830; }","duration":"101.374155ms","start":"2026-02-05T17:45:50.328601Z","end":"2026-02-05T17:45:50.429975Z","steps":["trace[1894708311] 'read index received' (duration: 44.459502ms)","trace[1894708311] 'applied index is now lower than readState.Index' (duration: 56.913313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:45:50.432723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.073233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a5bfe486-2b36-4a13-8aca-fa2802b04715\" ","response":"range_response_count:1 size:2174"} {"level":"info","ts":"2026-02-05T17:45:50.432834Z","caller":"traceutil/trace.go:171","msg":"trace[1587756284] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a5bfe486-2b36-4a13-8aca-fa2802b04715; range_end:; response_count:1; response_revision:1758; }","duration":"104.235287ms","start":"2026-02-05T17:45:50.328581Z","end":"2026-02-05T17:45:50.432816Z","steps":["trace[1587756284] 'agreement among raft nodes before linearized reading' (duration: 103.946599ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:45:50.430179Z","caller":"traceutil/trace.go:171","msg":"trace[1683859704] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"101.57538ms","start":"2026-02-05T17:45:50.328577Z","end":"2026-02-05T17:45:50.430153Z","steps":["trace[1683859704] 'process raft request' (duration: 44.473701ms)","trace[1683859704] 'compare' (duration: 56.820111ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:45:50.433778Z","caller":"traceutil/trace.go:171","msg":"trace[1716600077] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"102.158047ms","start":"2026-02-05T17:45:50.331604Z","end":"2026-02-05T17:45:50.433762Z","steps":["trace[1716600077] 'process raft request' (duration: 101.609221ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:46:03.161405Z","caller":"traceutil/trace.go:171","msg":"trace[1088848391] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"163.993853ms","start":"2026-02-05T17:46:02.997151Z","end":"2026-02-05T17:46:03.161144Z","steps":["trace[1088848391] 'process raft request' (duration: 163.839349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:46:08.814411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.330779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:46:08.816833Z","caller":"traceutil/trace.go:171","msg":"trace[892385764] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"180.006368ms","start":"2026-02-05T17:46:08.636806Z","end":"2026-02-05T17:46:08.816812Z","steps":["trace[892385764] 'process raft request' (duration: 72.150395ms)","trace[892385764] 'compare' (duration: 105.103043ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:46:09.04906Z","caller":"traceutil/trace.go:171","msg":"trace[1362104846] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"210.910935ms","start":"2026-02-05T17:46:08.838126Z","end":"2026-02-05T17:46:09.049037Z","steps":["trace[1362104846] 'process raft request' (duration: 207.663331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:46:09.292179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.43075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T17:46:09.292476Z","caller":"traceutil/trace.go:171","msg":"trace[1953830639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"107.76499ms","start":"2026-02-05T17:46:09.184692Z","end":"2026-02-05T17:46:09.292457Z","steps":["trace[1953830639] 'range keys from in-memory index tree' (duration: 107.293756ms)"],"step_count":1}