{"level":"warn","ts":"2026-01-29T18:42:07.016699Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T18:42:07.016809Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-29T18:42:07.01689Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T18:42:07.016927Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-01-29T18:42:07.016947Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-29T18:42:07.018236Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-01-29T18:42:07.019172Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-29T18:42:07.030253Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.844903ms"} {"level":"info","ts":"2026-01-29T18:42:07.059699Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-01-29T18:42:07.059767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T18:42:07.059783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-01-29T18:42:07.059793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T18:42:07.059799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-01-29T18:42:07.059826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-01-29T18:42:07.101837Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T18:42:07.120266Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T18:42:07.12326Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-29T18:42:07.127957Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T18:42:07.158115Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-29T18:42:07.158628Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T18:42:07.158728Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T18:42:07.158903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-01-29T18:42:07.158997Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-01-29T18:42:07.158966Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T18:42:07.159252Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T18:42:07.159332Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T18:42:07.159341Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T18:42:07.206776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-01-29T18:42:07.206911Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-01-29T18:42:07.760489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T18:42:07.760558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T18:42:07.760584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-01-29T18:42:07.760601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-01-29T18:42:07.760609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-01-29T18:42:07.760622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-01-29T18:42:07.760637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-01-29T18:42:07.805924Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T18:42:07.806182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:42:07.806376Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T18:42:07.806447Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T18:42:07.806534Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:42:07.806309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T18:42:07.813639Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T18:42:07.824425Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:42:07.827348Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:42:07.82741Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T18:42:07.827801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"warn","ts":"2026-01-29T18:42:11.965601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.637719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:42:11.966089Z","caller":"traceutil/trace.go:171","msg":"trace[858798577] linearizableReadLoop","detail":"{readStateIndex:183; appliedIndex:182; }","duration":"220.005285ms","start":"2026-01-29T18:42:11.746056Z","end":"2026-01-29T18:42:11.966061Z","steps":["trace[858798577] 'read index received' (duration: 65.182µs)","trace[858798577] 'applied index is now lower than readState.Index' (duration: 219.938573ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:11.966113Z","caller":"traceutil/trace.go:171","msg":"trace[1684860964] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"985.384669ms","start":"2026-01-29T18:42:10.98069Z","end":"2026-01-29T18:42:11.966075Z","steps":["trace[1684860964] 'process raft request' (duration: 757.903638ms)","trace[1684860964] 'compare' (duration: 225.440114ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:11.966396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:10.980607Z","time spent":"985.670916ms","remote":"127.0.0.1:36384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":664,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T18:42:11.966647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.514249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:11.966794Z","caller":"traceutil/trace.go:171","msg":"trace[482836477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:178; }","duration":"220.788027ms","start":"2026-01-29T18:42:11.745988Z","end":"2026-01-29T18:42:11.966776Z","steps":["trace[482836477] 'agreement among raft nodes before linearized reading' (duration: 220.360815ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.171504Z","caller":"traceutil/trace.go:171","msg":"trace[1823140015] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"146.235355ms","start":"2026-01-29T18:42:14.025248Z","end":"2026-01-29T18:42:14.171483Z","steps":["trace[1823140015] 'process raft request' (duration: 97.185133ms)","trace[1823140015] 'compare' (duration: 48.499338ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:14.171664Z","caller":"traceutil/trace.go:171","msg":"trace[525250086] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"144.274713ms","start":"2026-01-29T18:42:14.02738Z","end":"2026-01-29T18:42:14.171655Z","steps":["trace[525250086] 'process raft request' (duration: 143.730629ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.17181Z","caller":"traceutil/trace.go:171","msg":"trace[1034321030] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"126.780285ms","start":"2026-01-29T18:42:14.045019Z","end":"2026-01-29T18:42:14.171799Z","steps":["trace[1034321030] 'process raft request' (duration: 126.126298ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.171968Z","caller":"traceutil/trace.go:171","msg":"trace[1811459541] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:292; }","duration":"100.361567ms","start":"2026-01-29T18:42:14.071597Z","end":"2026-01-29T18:42:14.171959Z","steps":["trace[1811459541] 'read index received' (duration: 8.044355ms)","trace[1811459541] 'applied index is now lower than readState.Index' (duration: 92.315882ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:14.172221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.581563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-01-29T18:42:14.172248Z","caller":"traceutil/trace.go:171","msg":"trace[2037500979] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:291; }","duration":"100.670595ms","start":"2026-01-29T18:42:14.071568Z","end":"2026-01-29T18:42:14.172238Z","steps":["trace[2037500979] 'agreement among raft nodes before linearized reading' (duration: 100.580303ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:14.506191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.034788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:42:14.506433Z","caller":"traceutil/trace.go:171","msg":"trace[1946486481] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:300; }","duration":"239.935665ms","start":"2026-01-29T18:42:14.266478Z","end":"2026-01-29T18:42:14.506413Z","steps":["trace[1946486481] 'read index received' (duration: 15.512206ms)","trace[1946486481] 'applied index is now lower than readState.Index' (duration: 224.421849ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:14.506597Z","caller":"traceutil/trace.go:171","msg":"trace[1476488070] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"297.816485ms","start":"2026-01-29T18:42:14.208767Z","end":"2026-01-29T18:42:14.506583Z","steps":["trace[1476488070] 'process raft request' (duration: 73.235471ms)","trace[1476488070] 'compare' (duration: 223.852744ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:14.507009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.312556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-29T18:42:14.507107Z","caller":"traceutil/trace.go:171","msg":"trace[383701912] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"317.438559ms","start":"2026-01-29T18:42:14.189657Z","end":"2026-01-29T18:42:14.507095Z","steps":["trace[383701912] 'agreement among raft nodes before linearized reading' (duration: 317.236774ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:14.507238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:14.189647Z","time spent":"317.545672ms","remote":"127.0.0.1:36130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-01-29T18:42:14.507398Z","caller":"traceutil/trace.go:171","msg":"trace[877380637] transaction","detail":"{read_only:false; number_of_response:0; response_revision:295; }","duration":"166.849959ms","start":"2026-01-29T18:42:14.340536Z","end":"2026-01-29T18:42:14.507386Z","steps":["trace[877380637] 'process raft request' (duration: 166.379767ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.514586Z","caller":"traceutil/trace.go:171","msg":"trace[1997804295] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"173.62746ms","start":"2026-01-29T18:42:14.340945Z","end":"2026-01-29T18:42:14.514572Z","steps":["trace[1997804295] 'process raft request' (duration: 173.319141ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.514801Z","caller":"traceutil/trace.go:171","msg":"trace[1730246404] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"115.685749ms","start":"2026-01-29T18:42:14.399102Z","end":"2026-01-29T18:42:14.514788Z","steps":["trace[1730246404] 'process raft request' (duration: 115.37489ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.514967Z","caller":"traceutil/trace.go:171","msg":"trace[1608927996] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"115.35751ms","start":"2026-01-29T18:42:14.399468Z","end":"2026-01-29T18:42:14.514826Z","steps":["trace[1608927996] 'process raft request' (duration: 115.042362ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.515143Z","caller":"traceutil/trace.go:171","msg":"trace[1040125258] transaction","detail":"{read_only:false; number_of_response:0; response_revision:298; }","duration":"115.204225ms","start":"2026-01-29T18:42:14.399901Z","end":"2026-01-29T18:42:14.515105Z","steps":["trace[1040125258] 'process raft request' (duration: 114.63249ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.515326Z","caller":"traceutil/trace.go:171","msg":"trace[1961468699] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"119.182032ms","start":"2026-01-29T18:42:14.39611Z","end":"2026-01-29T18:42:14.515292Z","steps":["trace[1961468699] 'process raft request' (duration: 118.293968ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.515645Z","caller":"traceutil/trace.go:171","msg":"trace[1819756891] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"117.820235ms","start":"2026-01-29T18:42:14.397814Z","end":"2026-01-29T18:42:14.515634Z","steps":["trace[1819756891] 'process raft request' (duration: 116.618613ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:14.515664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.38831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T18:42:14.51591Z","caller":"traceutil/trace.go:171","msg":"trace[1773005860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:298; }","duration":"221.640526ms","start":"2026-01-29T18:42:14.294258Z","end":"2026-01-29T18:42:14.515898Z","steps":["trace[1773005860] 'agreement among raft nodes before linearized reading' (duration: 221.177904ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:14.671402Z","caller":"traceutil/trace.go:171","msg":"trace[321867678] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"102.514135ms","start":"2026-01-29T18:42:14.568857Z","end":"2026-01-29T18:42:14.671372Z","steps":["trace[321867678] 'process raft request' (duration: 42.271232ms)","trace[321867678] 'compare' (duration: 59.974995ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:17.709804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504127485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T18:42:17.790593Z","caller":"traceutil/trace.go:171","msg":"trace[1544964884] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"844.674007ms","start":"2026-01-29T18:42:16.945865Z","end":"2026-01-29T18:42:17.790539Z","steps":["trace[1544964884] 'process raft request' (duration: 844.40863ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:17.790957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:16.945839Z","time spent":"844.893573ms","remote":"127.0.0.1:36208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":217,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:42:17.790897Z","caller":"traceutil/trace.go:171","msg":"trace[316263886] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:348; }","duration":"582.10528ms","start":"2026-01-29T18:42:17.208777Z","end":"2026-01-29T18:42:17.790882Z","steps":["trace[316263886] 'read index received' (duration: 582.09711ms)","trace[316263886] 'applied index is now lower than readState.Index' (duration: 6.56µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:17.791261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.55877ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:17.791579Z","caller":"traceutil/trace.go:171","msg":"trace[774370084] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:338; }","duration":"235.752645ms","start":"2026-01-29T18:42:17.555685Z","end":"2026-01-29T18:42:17.791437Z","steps":["trace[774370084] 'agreement among raft nodes before linearized reading' (duration: 235.397326ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:17.791781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.999203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-01-29T18:42:17.791812Z","caller":"traceutil/trace.go:171","msg":"trace[1660132381] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:338; }","duration":"583.055844ms","start":"2026-01-29T18:42:17.208746Z","end":"2026-01-29T18:42:17.791802Z","steps":["trace[1660132381] 'agreement among raft nodes before linearized reading' (duration: 582.988433ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:17.79184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:17.20873Z","time spent":"583.102905ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4279,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-01-29T18:42:26.751043Z","caller":"traceutil/trace.go:171","msg":"trace[914624310] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"469.00998ms","start":"2026-01-29T18:42:26.281147Z","end":"2026-01-29T18:42:26.750157Z","steps":["trace[914624310] 'process raft request' (duration: 468.794435ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:26.752604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:26.281136Z","time spent":"470.964488ms","remote":"127.0.0.1:36266","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-01-29T18:42:26.758592Z","caller":"traceutil/trace.go:171","msg":"trace[1636139366] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:433; }","duration":"171.831787ms","start":"2026-01-29T18:42:26.586734Z","end":"2026-01-29T18:42:26.758566Z","steps":["trace[1636139366] 'read index received' (duration: 163.139329ms)","trace[1636139366] 'applied index is now lower than readState.Index' (duration: 8.691358ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:26.758728Z","caller":"traceutil/trace.go:171","msg":"trace[809132852] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"475.400733ms","start":"2026-01-29T18:42:26.2833Z","end":"2026-01-29T18:42:26.758718Z","steps":["trace[809132852] 'process raft request' (duration: 475.083375ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:26.759103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:26.283292Z","time spent":"475.472895ms","remote":"127.0.0.1:36266","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-01-29T18:42:26.759869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.52047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T18:42:26.760553Z","caller":"traceutil/trace.go:171","msg":"trace[342525757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"104.861713ms","start":"2026-01-29T18:42:26.655651Z","end":"2026-01-29T18:42:26.760513Z","steps":["trace[342525757] 'agreement among raft nodes before linearized reading' (duration: 103.527071ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:26.761441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.680045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:26.761928Z","caller":"traceutil/trace.go:171","msg":"trace[82708723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"175.25108ms","start":"2026-01-29T18:42:26.586667Z","end":"2026-01-29T18:42:26.761919Z","steps":["trace[82708723] 'agreement among raft nodes before linearized reading' (duration: 174.724727ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:29.270612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504127666,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:42:29.778137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504127666,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:42:30.278428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504127666,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:42:30.475406Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.713803051s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T18:42:30.476239Z","caller":"traceutil/trace.go:171","msg":"trace[546776260] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"1.714745242s","start":"2026-01-29T18:42:28.761465Z","end":"2026-01-29T18:42:30.476211Z","steps":["trace[546776260] 'process raft request' (duration: 1.714587789s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:30.476962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:28.761448Z","time spent":"1.715428139s","remote":"127.0.0.1:36266","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-01-29T18:42:30.476733Z","caller":"traceutil/trace.go:171","msg":"trace[900203381] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:440; }","duration":"1.707211675s","start":"2026-01-29T18:42:28.769506Z","end":"2026-01-29T18:42:30.476717Z","steps":["trace[900203381] 'read index received' (duration: 1.707204005s)","trace[900203381] 'applied index is now lower than readState.Index' (duration: 6.9µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:30.476823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707290358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T18:42:30.478756Z","caller":"traceutil/trace.go:171","msg":"trace[1056365479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"1.708993786s","start":"2026-01-29T18:42:28.769482Z","end":"2026-01-29T18:42:30.478476Z","steps":["trace[1056365479] 'agreement among raft nodes before linearized reading' (duration: 1.707282187s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:30.479618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:28.769474Z","time spent":"1.710126483s","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T18:42:30.831416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.37491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:42:30.831927Z","caller":"traceutil/trace.go:171","msg":"trace[961780031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:428; }","duration":"872.953253ms","start":"2026-01-29T18:42:29.958966Z","end":"2026-01-29T18:42:30.831919Z","steps":["trace[961780031] 'agreement among raft nodes before linearized reading' (duration: 872.35814ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:30.83204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:29.958939Z","time spent":"873.092166ms","remote":"127.0.0.1:36266","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-01-29T18:42:30.831734Z","caller":"traceutil/trace.go:171","msg":"trace[1789743438] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"353.028321ms","start":"2026-01-29T18:42:30.47825Z","end":"2026-01-29T18:42:30.831278Z","steps":["trace[1789743438] 'read index received' (duration: 333.10961ms)","trace[1789743438] 'applied index is now lower than readState.Index' (duration: 19.917331ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:30.831805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.058674869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T18:42:30.832483Z","caller":"traceutil/trace.go:171","msg":"trace[1941745061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:428; }","duration":"2.059361664s","start":"2026-01-29T18:42:28.773114Z","end":"2026-01-29T18:42:30.832475Z","steps":["trace[1941745061] 'agreement among raft nodes before linearized reading' (duration: 2.058662328s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:30.832569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:28.773109Z","time spent":"2.059451017s","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T18:42:30.831842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109953836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-01-29T18:42:30.832798Z","caller":"traceutil/trace.go:171","msg":"trace[1798291129] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:428; }","duration":"1.110939028s","start":"2026-01-29T18:42:29.721852Z","end":"2026-01-29T18:42:30.832791Z","steps":["trace[1798291129] 'agreement among raft nodes before linearized reading' (duration: 1.109964295s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:30.83291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:29.721832Z","time spent":"1.111067392s","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-01-29T18:42:30.831866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246160244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:30.833196Z","caller":"traceutil/trace.go:171","msg":"trace[1714721927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"1.247541466s","start":"2026-01-29T18:42:29.585647Z","end":"2026-01-29T18:42:30.833188Z","steps":["trace[1714721927] 'agreement among raft nodes before linearized reading' (duration: 1.246204445s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:30.833294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:29.585625Z","time spent":"1.24766195s","remote":"127.0.0.1:36016","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-01-29T18:42:30.833709Z","caller":"traceutil/trace.go:171","msg":"trace[1537148235] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"409.889227ms","start":"2026-01-29T18:42:30.423809Z","end":"2026-01-29T18:42:30.833698Z","steps":["trace[1537148235] 'process raft request' (duration: 387.440817ms)","trace[1537148235] 'compare' (duration: 19.910911ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:30.834143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:30.423693Z","time spent":"410.111812ms","remote":"127.0.0.1:36266","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-01-29T18:42:30.847056Z","caller":"traceutil/trace.go:171","msg":"trace[747738974] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"363.137125ms","start":"2026-01-29T18:42:30.483897Z","end":"2026-01-29T18:42:30.847034Z","steps":["trace[747738974] 'process raft request' (duration: 363.000432ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:30.847485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:30.483874Z","time spent":"363.518924ms","remote":"127.0.0.1:36266","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-01-29T18:42:31.193496Z","caller":"traceutil/trace.go:171","msg":"trace[1448069716] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"350.68244ms","start":"2026-01-29T18:42:30.842786Z","end":"2026-01-29T18:42:31.193469Z","steps":["trace[1448069716] 'process raft request' (duration: 350.607408ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:31.193996Z","caller":"traceutil/trace.go:171","msg":"trace[685100400] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"353.329282ms","start":"2026-01-29T18:42:30.840647Z","end":"2026-01-29T18:42:31.193976Z","steps":["trace[685100400] 'process raft request' (duration: 338.4465ms)","trace[685100400] 'compare' (duration: 12.6387ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:31.19573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:30.84061Z","time spent":"355.046211ms","remote":"127.0.0.1:36266","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-01-29T18:42:31.195925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:30.842779Z","time spent":"352.796598ms","remote":"127.0.0.1:36266","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-01-29T18:42:31.288919Z","caller":"traceutil/trace.go:171","msg":"trace[480376136] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:442; }","duration":"434.159653ms","start":"2026-01-29T18:42:30.854734Z","end":"2026-01-29T18:42:31.288894Z","steps":["trace[480376136] 'read index received' (duration: 324.372544ms)","trace[480376136] 'applied index is now lower than readState.Index' (duration: 109.785949ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:31.289352Z","caller":"traceutil/trace.go:171","msg":"trace[1638998524] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"434.687626ms","start":"2026-01-29T18:42:30.854633Z","end":"2026-01-29T18:42:31.28932Z","steps":["trace[1638998524] 'process raft request' (duration: 434.092302ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:31.289574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:30.854611Z","time spent":"434.807798ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:42:31.289744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.84201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:31.289867Z","caller":"traceutil/trace.go:171","msg":"trace[1669108918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"435.148916ms","start":"2026-01-29T18:42:30.854705Z","end":"2026-01-29T18:42:31.289854Z","steps":["trace[1669108918] 'agreement among raft nodes before linearized reading' (duration: 434.667715ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:31.290002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:30.854696Z","time spent":"435.293779ms","remote":"127.0.0.1:36016","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-01-29T18:42:31.463937Z","caller":"traceutil/trace.go:171","msg":"trace[1064830662] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"158.152859ms","start":"2026-01-29T18:42:31.305764Z","end":"2026-01-29T18:42:31.463917Z","steps":["trace[1064830662] 'process raft request' (duration: 158.038687ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:31.657958Z","caller":"traceutil/trace.go:171","msg":"trace[1171115788] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"183.749986ms","start":"2026-01-29T18:42:31.474189Z","end":"2026-01-29T18:42:31.657939Z","steps":["trace[1171115788] 'process raft request' (duration: 183.711255ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:31.658261Z","caller":"traceutil/trace.go:171","msg":"trace[1807998739] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"185.769902ms","start":"2026-01-29T18:42:31.472482Z","end":"2026-01-29T18:42:31.658252Z","steps":["trace[1807998739] 'process raft request' (duration: 127.810993ms)","trace[1807998739] 'compare' (duration: 57.462878ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:31.897531Z","caller":"traceutil/trace.go:171","msg":"trace[690101956] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"155.920058ms","start":"2026-01-29T18:42:31.741577Z","end":"2026-01-29T18:42:31.897497Z","steps":["trace[690101956] 'process raft request' (duration: 105.042501ms)","trace[690101956] 'compare' (duration: 50.719053ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:31.897673Z","caller":"traceutil/trace.go:171","msg":"trace[362318633] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"140.254718ms","start":"2026-01-29T18:42:31.757396Z","end":"2026-01-29T18:42:31.897651Z","steps":["trace[362318633] 'process raft request' (duration: 140.215197ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:31.897858Z","caller":"traceutil/trace.go:171","msg":"trace[1967411536] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:455; }","duration":"155.225742ms","start":"2026-01-29T18:42:31.742625Z","end":"2026-01-29T18:42:31.89785Z","steps":["trace[1967411536] 'read index received' (duration: 10.537391ms)","trace[1967411536] 'applied index is now lower than readState.Index' (duration: 144.687711ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:31.898156Z","caller":"traceutil/trace.go:171","msg":"trace[401517283] transaction","detail":"{read_only:false; number_of_response:1; response_revision:444; }","duration":"143.299708ms","start":"2026-01-29T18:42:31.75485Z","end":"2026-01-29T18:42:31.89815Z","steps":["trace[401517283] 'process raft request' (duration: 142.676444ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:31.898289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.646102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-01-29T18:42:31.898316Z","caller":"traceutil/trace.go:171","msg":"trace[414556321] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:445; }","duration":"155.697743ms","start":"2026-01-29T18:42:31.742611Z","end":"2026-01-29T18:42:31.898309Z","steps":["trace[414556321] 'agreement among raft nodes before linearized reading' (duration: 155.634602ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:31.898421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.997773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-01-29T18:42:31.898621Z","caller":"traceutil/trace.go:171","msg":"trace[2059063047] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:445; }","duration":"154.208118ms","start":"2026-01-29T18:42:31.744408Z","end":"2026-01-29T18:42:31.898617Z","steps":["trace[2059063047] 'agreement among raft nodes before linearized reading' (duration: 153.992873ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:32.12424Z","caller":"traceutil/trace.go:171","msg":"trace[195716168] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"139.58854ms","start":"2026-01-29T18:42:31.984622Z","end":"2026-01-29T18:42:32.12421Z","steps":["trace[195716168] 'process raft request' (duration: 139.520249ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:32.124462Z","caller":"traceutil/trace.go:171","msg":"trace[574537808] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"141.034864ms","start":"2026-01-29T18:42:31.983416Z","end":"2026-01-29T18:42:32.124451Z","steps":["trace[574537808] 'process raft request' (duration: 106.64128ms)","trace[574537808] 'compare' (duration: 33.932413ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:33.324656Z","caller":"traceutil/trace.go:171","msg":"trace[1833672384] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"115.633308ms","start":"2026-01-29T18:42:33.208995Z","end":"2026-01-29T18:42:33.324629Z","steps":["trace[1833672384] 'process raft request' (duration: 87.005353ms)","trace[1833672384] 'compare' (duration: 28.520263ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:35.422358Z","caller":"traceutil/trace.go:171","msg":"trace[653602668] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"178.222134ms","start":"2026-01-29T18:42:35.244113Z","end":"2026-01-29T18:42:35.422335Z","steps":["trace[653602668] 'read index received' (duration: 178.06104ms)","trace[653602668] 'applied index is now lower than readState.Index' (duration: 160.444µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:35.42256Z","caller":"traceutil/trace.go:171","msg":"trace[737370835] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"536.30786ms","start":"2026-01-29T18:42:34.886246Z","end":"2026-01-29T18:42:35.422553Z","steps":["trace[737370835] 'process raft request' (duration: 535.986053ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:35.422632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:34.886211Z","time spent":"536.36613ms","remote":"127.0.0.1:36266","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-01-29T18:42:35.422759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.644433ms","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-01-29T18:42:35.422776Z","caller":"traceutil/trace.go:171","msg":"trace[241071784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"178.698014ms","start":"2026-01-29T18:42:35.244073Z","end":"2026-01-29T18:42:35.422771Z","steps":["trace[241071784] 'agreement among raft nodes before linearized reading' (duration: 178.664364ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:41.609021Z","caller":"traceutil/trace.go:171","msg":"trace[2068873948] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"135.114944ms","start":"2026-01-29T18:42:41.473883Z","end":"2026-01-29T18:42:41.608998Z","steps":["trace[2068873948] 'process raft request' (duration: 135.080443ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:41.609407Z","caller":"traceutil/trace.go:171","msg":"trace[1833637080] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"136.759669ms","start":"2026-01-29T18:42:41.47264Z","end":"2026-01-29T18:42:41.609399Z","steps":["trace[1833637080] 'process raft request' (duration: 136.203527ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:48.751444Z","caller":"traceutil/trace.go:171","msg":"trace[1180333303] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"165.548083ms","start":"2026-01-29T18:42:48.585879Z","end":"2026-01-29T18:42:48.751427Z","steps":["trace[1180333303] 'process raft request' (duration: 165.435601ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:48.751547Z","caller":"traceutil/trace.go:171","msg":"trace[262907527] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:575; }","duration":"159.499383ms","start":"2026-01-29T18:42:48.592017Z","end":"2026-01-29T18:42:48.751516Z","steps":["trace[262907527] 'read index received' (duration: 159.489862ms)","trace[262907527] 'applied index is now lower than readState.Index' (duration: 7.431µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:42:48.75178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.740737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:48.751809Z","caller":"traceutil/trace.go:171","msg":"trace[1900600956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"159.820928ms","start":"2026-01-29T18:42:48.59198Z","end":"2026-01-29T18:42:48.751801Z","steps":["trace[1900600956] 'agreement among raft nodes before linearized reading' (duration: 159.689516ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:48.758692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.436921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T18:42:48.75873Z","caller":"traceutil/trace.go:171","msg":"trace[86814599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"115.529563ms","start":"2026-01-29T18:42:48.64319Z","end":"2026-01-29T18:42:48.758719Z","steps":["trace[86814599] 'agreement among raft nodes before linearized reading' (duration: 115.338739ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:49.009811Z","caller":"traceutil/trace.go:171","msg":"trace[2009591479] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"102.350977ms","start":"2026-01-29T18:42:48.907435Z","end":"2026-01-29T18:42:49.009786Z","steps":["trace[2009591479] 'process raft request' (duration: 102.221935ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:49.866226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504127935,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T18:42:49.9782Z","caller":"traceutil/trace.go:171","msg":"trace[1878061031] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"671.951687ms","start":"2026-01-29T18:42:49.306227Z","end":"2026-01-29T18:42:49.978179Z","steps":["trace[1878061031] 'process raft request' (duration: 671.832035ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:49.978625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:49.306206Z","time spent":"672.364516ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:42:49.985785Z","caller":"traceutil/trace.go:171","msg":"trace[268673761] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"641.35281ms","start":"2026-01-29T18:42:49.34441Z","end":"2026-01-29T18:42:49.985763Z","steps":["trace[268673761] 'read index received' (duration: 633.82008ms)","trace[268673761] 'applied index is now lower than readState.Index' (duration: 7.53202ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:49.985964Z","caller":"traceutil/trace.go:171","msg":"trace[1467305470] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"480.194069ms","start":"2026-01-29T18:42:49.505755Z","end":"2026-01-29T18:42:49.985949Z","steps":["trace[1467305470] 'process raft request' (duration: 479.75812ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:49.986093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:49.505727Z","time spent":"480.336062ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-29T18:42:49.986097Z","caller":"traceutil/trace.go:171","msg":"trace[646149932] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"279.030572ms","start":"2026-01-29T18:42:49.707055Z","end":"2026-01-29T18:42:49.986085Z","steps":["trace[646149932] 'process raft request' (duration: 278.658365ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:49.986268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.83479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:49.987409Z","caller":"traceutil/trace.go:171","msg":"trace[1506352244] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:566; }","duration":"643.006162ms","start":"2026-01-29T18:42:49.344376Z","end":"2026-01-29T18:42:49.987382Z","steps":["trace[1506352244] 'agreement among raft nodes before linearized reading' (duration: 641.744117ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:49.987517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:49.344357Z","time spent":"643.142245ms","remote":"127.0.0.1:36104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-01-29T18:42:49.999466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.667246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:49.999539Z","caller":"traceutil/trace.go:171","msg":"trace[1736598397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"411.7781ms","start":"2026-01-29T18:42:49.587742Z","end":"2026-01-29T18:42:49.999521Z","steps":["trace[1736598397] 'agreement among raft nodes before linearized reading' (duration: 411.651626ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:50.000619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:49.587725Z","time spent":"412.885601ms","remote":"127.0.0.1:36016","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-01-29T18:42:50.000796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.619469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:42:50.000814Z","caller":"traceutil/trace.go:171","msg":"trace[2030675546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"251.655669ms","start":"2026-01-29T18:42:49.749152Z","end":"2026-01-29T18:42:50.000808Z","steps":["trace[2030675546] 'agreement among raft nodes before linearized reading' (duration: 251.577857ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:50.000964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.579865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T18:42:50.000981Z","caller":"traceutil/trace.go:171","msg":"trace[1346112000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"264.627756ms","start":"2026-01-29T18:42:49.736348Z","end":"2026-01-29T18:42:50.000975Z","steps":["trace[1346112000] 'agreement among raft nodes before linearized reading' (duration: 264.576965ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:50.001123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.623421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T18:42:50.001141Z","caller":"traceutil/trace.go:171","msg":"trace[145583356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"381.680901ms","start":"2026-01-29T18:42:49.619454Z","end":"2026-01-29T18:42:50.001135Z","steps":["trace[145583356] 'agreement among raft nodes before linearized reading' (duration: 381.60446ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:50.001174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:49.619427Z","time spent":"381.742153ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T18:42:52.229122Z","caller":"traceutil/trace.go:171","msg":"trace[179907452] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"152.39053ms","start":"2026-01-29T18:42:52.076712Z","end":"2026-01-29T18:42:52.229102Z","steps":["trace[179907452] 'process raft request' (duration: 152.261557ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:52.236355Z","caller":"traceutil/trace.go:171","msg":"trace[1148877935] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"104.416258ms","start":"2026-01-29T18:42:52.131923Z","end":"2026-01-29T18:42:52.236339Z","steps":["trace[1148877935] 'read index received' (duration: 97.377901ms)","trace[1148877935] 'applied index is now lower than readState.Index' (duration: 7.037537ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:52.236903Z","caller":"traceutil/trace.go:171","msg":"trace[607777981] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"136.477001ms","start":"2026-01-29T18:42:52.100418Z","end":"2026-01-29T18:42:52.236895Z","steps":["trace[607777981] 'process raft request' (duration: 135.521613ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:52.237329Z","caller":"traceutil/trace.go:171","msg":"trace[9436841] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"105.221273ms","start":"2026-01-29T18:42:52.132084Z","end":"2026-01-29T18:42:52.237305Z","steps":["trace[9436841] 'process raft request' (duration: 103.945749ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.237997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.05893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-01-29T18:42:52.238025Z","caller":"traceutil/trace.go:171","msg":"trace[1660543370] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"106.121862ms","start":"2026-01-29T18:42:52.131894Z","end":"2026-01-29T18:42:52.238016Z","steps":["trace[1660543370] 'agreement among raft nodes before linearized reading' (duration: 106.02324ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:52.238116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.069241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-29T18:42:52.23813Z","caller":"traceutil/trace.go:171","msg":"trace[591023151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:609; }","duration":"106.091091ms","start":"2026-01-29T18:42:52.132034Z","end":"2026-01-29T18:42:52.238125Z","steps":["trace[591023151] 'agreement among raft nodes before linearized reading' (duration: 106.04363ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:56.47217Z","caller":"traceutil/trace.go:171","msg":"trace[628341399] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"220.426782ms","start":"2026-01-29T18:42:56.251725Z","end":"2026-01-29T18:42:56.472152Z","steps":["trace[628341399] 'read index received' (duration: 220.27481ms)","trace[628341399] 'applied index is now lower than readState.Index' (duration: 151.452µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:42:56.472411Z","caller":"traceutil/trace.go:171","msg":"trace[1208940452] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"232.970979ms","start":"2026-01-29T18:42:56.239431Z","end":"2026-01-29T18:42:56.472402Z","steps":["trace[1208940452] 'process raft request' (duration: 232.610422ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:56.472572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.83398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T18:42:56.472588Z","caller":"traceutil/trace.go:171","msg":"trace[1057994093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"220.87661ms","start":"2026-01-29T18:42:56.251706Z","end":"2026-01-29T18:42:56.472583Z","steps":["trace[1057994093] 'agreement among raft nodes before linearized reading' (duration: 220.798649ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:56.472672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.264698ms","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-01-29T18:42:56.472684Z","caller":"traceutil/trace.go:171","msg":"trace[686500098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"220.287829ms","start":"2026-01-29T18:42:56.252392Z","end":"2026-01-29T18:42:56.47268Z","steps":["trace[686500098] 'agreement among raft nodes before linearized reading' (duration: 220.249838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:56.472795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.135784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-29T18:42:56.47281Z","caller":"traceutil/trace.go:171","msg":"trace[1518363821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:620; }","duration":"177.172885ms","start":"2026-01-29T18:42:56.295631Z","end":"2026-01-29T18:42:56.472804Z","steps":["trace[1518363821] 'agreement among raft nodes before linearized reading' (duration: 177.135915ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:42:57.132849Z","caller":"traceutil/trace.go:171","msg":"trace[1230743462] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"241.024505ms","start":"2026-01-29T18:42:56.891808Z","end":"2026-01-29T18:42:57.132833Z","steps":["trace[1230743462] 'process raft request' (duration: 240.887212ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:58.086398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504128072,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:42:58.586744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504128072,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:42:58.712249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.196959ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:42:58.712357Z","caller":"traceutil/trace.go:171","msg":"trace[795587824] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:627; }","duration":"162.905953ms","start":"2026-01-29T18:42:58.549426Z","end":"2026-01-29T18:42:58.712332Z","steps":["trace[795587824] 'range keys from in-memory index tree' (duration: 162.180369ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:58.797323Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.42518101s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T18:42:58.798732Z","caller":"traceutil/trace.go:171","msg":"trace[640552643] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.426708588s","start":"2026-01-29T18:42:57.371996Z","end":"2026-01-29T18:42:58.798705Z","steps":["trace[640552643] 'process raft request' (duration: 1.425588977s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:58.798881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:57.371979Z","time spent":"1.426805821s","remote":"127.0.0.1:36190","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-01-29T18:42:59.586887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001162433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-29T18:42:59.586952Z","caller":"traceutil/trace.go:171","msg":"trace[650359065] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001257204s","start":"2026-01-29T18:42:57.585677Z","end":"2026-01-29T18:42:59.586935Z","steps":["trace[650359065] 'agreement among raft nodes before linearized reading' (duration: 2.001178143s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:42:59.586989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:57.585662Z","time spent":"2.001319915s","remote":"127.0.0.1:36030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/29 18:42:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-29T18:43:00.437839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.651745ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T18:43:00.437919Z","caller":"traceutil/trace.go:171","msg":"trace[60417271] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"2.852202896s","start":"2026-01-29T18:42:57.585702Z","end":"2026-01-29T18:43:00.437905Z","steps":["trace[60417271] 'read index received' (duration: 1.2140725s)","trace[60417271] 'applied index is now lower than readState.Index' (duration: 1.638128936s)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:00.438939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.960638812s","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-01-29T18:43:00.43897Z","caller":"traceutil/trace.go:171","msg":"trace[27110779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.960702962s","start":"2026-01-29T18:42:58.478259Z","end":"2026-01-29T18:43:00.438962Z","steps":["trace[27110779] 'agreement among raft nodes before linearized reading' (duration: 1.96055552s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:00.438994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:58.478244Z","time spent":"1.960744553s","remote":"127.0.0.1:36266","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":"info","ts":"2026-01-29T18:43:00.439128Z","caller":"traceutil/trace.go:171","msg":"trace[1624399343] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"849.837121ms","start":"2026-01-29T18:42:59.589284Z","end":"2026-01-29T18:43:00.439121Z","steps":["trace[1624399343] 'process raft request' (duration: 849.447504ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:00.439176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:59.589268Z","time spent":"849.877333ms","remote":"127.0.0.1:36080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T18:43:00.439271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.257243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:00.439289Z","caller":"traceutil/trace.go:171","msg":"trace[1250225957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"848.293423ms","start":"2026-01-29T18:42:59.590989Z","end":"2026-01-29T18:43:00.439283Z","steps":["trace[1250225957] 'agreement among raft nodes before linearized reading' (duration: 848.264773ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:00.439305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:59.590981Z","time spent":"848.320053ms","remote":"127.0.0.1:36016","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-01-29T18:43:00.439411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303343088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T18:43:00.439428Z","caller":"traceutil/trace.go:171","msg":"trace[1584969944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.303397489s","start":"2026-01-29T18:42:59.136025Z","end":"2026-01-29T18:43:00.439422Z","steps":["trace[1584969944] 'agreement among raft nodes before linearized reading' (duration: 1.303346898s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:00.439445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:59.136006Z","time spent":"1.3034343s","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T18:43:00.439552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93534788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-29T18:43:00.439568Z","caller":"traceutil/trace.go:171","msg":"trace[1765747175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.93537416s","start":"2026-01-29T18:42:58.504189Z","end":"2026-01-29T18:43:00.439563Z","steps":["trace[1765747175] 'agreement among raft nodes before linearized reading' (duration: 1.935323509s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:00.439587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:58.504184Z","time spent":"1.93539646s","remote":"127.0.0.1:36266","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-01-29T18:43:00.439709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.936522472s","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-01-29T18:43:00.439726Z","caller":"traceutil/trace.go:171","msg":"trace[277281251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"1.936554512s","start":"2026-01-29T18:42:58.503167Z","end":"2026-01-29T18:43:00.439721Z","steps":["trace[277281251] 'agreement among raft nodes before linearized reading' (duration: 1.936504581s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:00.439742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:58.503161Z","time spent":"1.936577212s","remote":"127.0.0.1:36266","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-01-29T18:43:00.439839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.938639801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-29T18:43:00.439855Z","caller":"traceutil/trace.go:171","msg":"trace[1218819845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"1.938675401s","start":"2026-01-29T18:42:58.501174Z","end":"2026-01-29T18:43:00.43985Z","steps":["trace[1218819845] 'agreement among raft nodes before linearized reading' (duration: 1.93862947s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:00.439872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:42:58.501157Z","time spent":"1.938709523s","remote":"127.0.0.1:36266","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-01-29T18:43:02.085385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504128078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:43:02.58624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420191504128078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T18:43:03.035844Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.583830839s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-29T18:43:03.585401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000402223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T18:43:03.585497Z","caller":"traceutil/trace.go:171","msg":"trace[864829042] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000508756s","start":"2026-01-29T18:43:01.584937Z","end":"2026-01-29T18:43:03.585446Z","steps":["trace[864829042] 'agreement among raft nodes before linearized reading' (duration: 2.000431174s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:03.585533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:01.584922Z","time spent":"2.000604057s","remote":"127.0.0.1:36016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:43:05.445201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:00.456393Z","time spent":"4.988802183s","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/01/29 18:43:05 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-29T18:43:05.451945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:00.456247Z","time spent":"4.995666906s","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-29T18:43:05.452694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:00.451938Z","time spent":"5.000753927s","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-29T18:43:05.585997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000162525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T18:43:05.586157Z","caller":"traceutil/trace.go:171","msg":"trace[1853400578] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000279647s","start":"2026-01-29T18:43:03.585782Z","end":"2026-01-29T18:43:05.586062Z","steps":["trace[1853400578] 'agreement among raft nodes before linearized reading' (duration: 2.000174726s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:05.586245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:03.585771Z","time spent":"2.000461s","remote":"127.0.0.1:36030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T18:43:06.483742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.447568638s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-01-29T18:43:07.117252Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.081206081s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T18:43:07.117768Z","caller":"traceutil/trace.go:171","msg":"trace[1572216169] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:650; }","duration":"5.532766384s","start":"2026-01-29T18:43:01.584976Z","end":"2026-01-29T18:43:07.117742Z","steps":["trace[1572216169] 'read index received' (duration: 1.451143305s)","trace[1572216169] 'applied index is now lower than readState.Index' (duration: 4.081622089s)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:07.118089Z","caller":"traceutil/trace.go:171","msg":"trace[168688163] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"6.661712747s","start":"2026-01-29T18:43:00.456363Z","end":"2026-01-29T18:43:07.118076Z","steps":["trace[168688163] 'process raft request' (duration: 6.661199478s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:07.118187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:00.456358Z","time spent":"6.661766649s","remote":"127.0.0.1:36266","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-01-29T18:43:07.118352Z","caller":"traceutil/trace.go:171","msg":"trace[1700734733] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"6.661920791s","start":"2026-01-29T18:43:00.456422Z","end":"2026-01-29T18:43:07.118342Z","steps":["trace[1700734733] 'process raft request' (duration: 6.661256329s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:07.118401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:00.456418Z","time spent":"6.661956272s","remote":"127.0.0.1:36266","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-01-29T18:43:07.118514Z","caller":"traceutil/trace.go:171","msg":"trace[1440651666] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"5.383595291s","start":"2026-01-29T18:43:01.734911Z","end":"2026-01-29T18:43:07.118507Z","steps":["trace[1440651666] 'process raft request' (duration: 5.382797687s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:07.118562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:01.734884Z","time spent":"5.383652792s","remote":"127.0.0.1:36266","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-01-29T18:43:07.176242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.726989806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T18:43:07.178927Z","caller":"traceutil/trace.go:171","msg":"trace[1562610774] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:636; }","duration":"4.729711934s","start":"2026-01-29T18:43:02.449201Z","end":"2026-01-29T18:43:07.178913Z","steps":["trace[1562610774] 'agreement among raft nodes before linearized reading' (duration: 4.726853534s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:07.179114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:02.449182Z","time spent":"4.729916637s","remote":"127.0.0.1:36048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"info","ts":"2026-01-29T18:43:07.176512Z","caller":"traceutil/trace.go:171","msg":"trace[1680203810] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.628727737s","start":"2026-01-29T18:43:05.547766Z","end":"2026-01-29T18:43:07.176494Z","steps":["trace[1680203810] 'process raft request' (duration: 1.625717675s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:07.179784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:05.547742Z","time spent":"1.631975284s","remote":"127.0.0.1:36266","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-01-29T18:43:07.178714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585640441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:07.180361Z","caller":"traceutil/trace.go:171","msg":"trace[649724291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.587311101s","start":"2026-01-29T18:43:05.59304Z","end":"2026-01-29T18:43:07.180352Z","steps":["trace[649724291] 'agreement among raft nodes before linearized reading' (duration: 1.58559338s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:07.18069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:05.593032Z","time spent":"1.587648476s","remote":"127.0.0.1:36016","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-01-29T18:43:07.179631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586709429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.188f47d9dafd41f9\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-01-29T18:43:07.18166Z","caller":"traceutil/trace.go:171","msg":"trace[1324395143] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.188f47d9dafd41f9; range_end:; response_count:1; response_revision:636; }","duration":"1.588775096s","start":"2026-01-29T18:43:05.592875Z","end":"2026-01-29T18:43:07.18165Z","steps":["trace[1324395143] 'agreement among raft nodes before linearized reading' (duration: 1.585896886s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:07.182854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:05.592859Z","time spent":"1.589984036s","remote":"127.0.0.1:36080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.188f47d9dafd41f9\" "} {"level":"info","ts":"2026-01-29T18:43:07.296584Z","caller":"traceutil/trace.go:171","msg":"trace[1314685268] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"111.307702ms","start":"2026-01-29T18:43:07.185236Z","end":"2026-01-29T18:43:07.296544Z","steps":["trace[1314685268] 'process raft request' (duration: 88.318389ms)","trace[1314685268] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/events/kube-system/kube-apiserver-instance.188f47d9dafd41f9; req_size:756; } (duration: 20.299656ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:07.296863Z","caller":"traceutil/trace.go:171","msg":"trace[1504620351] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"111.291613ms","start":"2026-01-29T18:43:07.185553Z","end":"2026-01-29T18:43:07.296845Z","steps":["trace[1504620351] 'read index received' (duration: 90.419207ms)","trace[1504620351] 'applied index is now lower than readState.Index' (duration: 20.871166ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:07.296938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.371494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:07.296958Z","caller":"traceutil/trace.go:171","msg":"trace[1853848311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"111.422725ms","start":"2026-01-29T18:43:07.185529Z","end":"2026-01-29T18:43:07.296952Z","steps":["trace[1853848311] 'agreement among raft nodes before linearized reading' (duration: 111.374124ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:10.773001Z","caller":"traceutil/trace.go:171","msg":"trace[1632700434] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"101.182652ms","start":"2026-01-29T18:43:10.671801Z","end":"2026-01-29T18:43:10.772984Z","steps":["trace[1632700434] 'process raft request' (duration: 101.085851ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:10.959326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.908075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:43:10.959469Z","caller":"traceutil/trace.go:171","msg":"trace[1794460478] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"157.832137ms","start":"2026-01-29T18:43:10.801612Z","end":"2026-01-29T18:43:10.959444Z","steps":["trace[1794460478] 'process raft request' (duration: 23.761479ms)","trace[1794460478] 'compare' (duration: 133.754282ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:11.670668Z","caller":"traceutil/trace.go:171","msg":"trace[1417787787] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"164.123847ms","start":"2026-01-29T18:43:11.50652Z","end":"2026-01-29T18:43:11.670643Z","steps":["trace[1417787787] 'read index received' (duration: 163.938914ms)","trace[1417787787] 'applied index is now lower than readState.Index' (duration: 184.253µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:11.67077Z","caller":"traceutil/trace.go:171","msg":"trace[126415942] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"178.724057ms","start":"2026-01-29T18:43:11.492038Z","end":"2026-01-29T18:43:11.670762Z","steps":["trace[126415942] 'process raft request' (duration: 178.481573ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:11.671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.462094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:43:11.671032Z","caller":"traceutil/trace.go:171","msg":"trace[1333336160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"164.518605ms","start":"2026-01-29T18:43:11.506504Z","end":"2026-01-29T18:43:11.671022Z","steps":["trace[1333336160] 'agreement among raft nodes before linearized reading' (duration: 164.397013ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:11.671227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.846794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-29T18:43:11.671291Z","caller":"traceutil/trace.go:171","msg":"trace[198663153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:667; }","duration":"163.963566ms","start":"2026-01-29T18:43:11.507311Z","end":"2026-01-29T18:43:11.671274Z","steps":["trace[198663153] 'agreement among raft nodes before linearized reading' (duration: 163.762082ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:11.890564Z","caller":"traceutil/trace.go:171","msg":"trace[1909345111] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"212.76686ms","start":"2026-01-29T18:43:11.677779Z","end":"2026-01-29T18:43:11.890546Z","steps":["trace[1909345111] 'process raft request' (duration: 171.87115ms)","trace[1909345111] 'compare' (duration: 40.770648ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:11.890688Z","caller":"traceutil/trace.go:171","msg":"trace[504326650] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"212.851561ms","start":"2026-01-29T18:43:11.677832Z","end":"2026-01-29T18:43:11.890684Z","steps":["trace[504326650] 'process raft request' (duration: 212.687769ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:13.041431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.265403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:43:13.041507Z","caller":"traceutil/trace.go:171","msg":"trace[737230551] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"654.146142ms","start":"2026-01-29T18:43:12.387347Z","end":"2026-01-29T18:43:13.041493Z","steps":["trace[737230551] 'read index received' (duration: 19.981µs)","trace[737230551] 'applied index is now lower than readState.Index' (duration: 654.125071ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:13.041585Z","caller":"traceutil/trace.go:171","msg":"trace[1517003040] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"746.241848ms","start":"2026-01-29T18:43:12.295336Z","end":"2026-01-29T18:43:13.041578Z","steps":["trace[1517003040] 'process raft request' (duration: 34.785902ms)","trace[1517003040] 'compare' (duration: 710.977948ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:13.041623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:12.29532Z","time spent":"746.282109ms","remote":"127.0.0.1:36080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T18:43:13.041824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.469917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jrsjx\" ","response":"range_response_count:1 size:17914"} {"level":"info","ts":"2026-01-29T18:43:13.041842Z","caller":"traceutil/trace.go:171","msg":"trace[621490360] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jrsjx; range_end:; response_count:1; response_revision:671; }","duration":"654.517887ms","start":"2026-01-29T18:43:12.387318Z","end":"2026-01-29T18:43:13.041836Z","steps":["trace[621490360] 'agreement among raft nodes before linearized reading' (duration: 654.408406ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:13.04186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:12.387303Z","time spent":"654.552959ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17937,"request content":"key:\"/registry/pods/kube-system/cilium-jrsjx\" "} {"level":"warn","ts":"2026-01-29T18:43:13.04199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.997664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T18:43:13.042007Z","caller":"traceutil/trace.go:171","msg":"trace[1297601725] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:671; }","duration":"592.035814ms","start":"2026-01-29T18:43:12.449966Z","end":"2026-01-29T18:43:13.042002Z","steps":["trace[1297601725] 'agreement among raft nodes before linearized reading' (duration: 591.987063ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:13.042022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:12.449956Z","time spent":"592.063745ms","remote":"127.0.0.1:36048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-01-29T18:43:13.042152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.305227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:13.042169Z","caller":"traceutil/trace.go:171","msg":"trace[1059651260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"456.357918ms","start":"2026-01-29T18:43:12.585806Z","end":"2026-01-29T18:43:13.042164Z","steps":["trace[1059651260] 'agreement among raft nodes before linearized reading' (duration: 456.332097ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:13.042184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:12.585786Z","time spent":"456.395238ms","remote":"127.0.0.1:36016","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-01-29T18:43:13.042262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.375246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:13.04228Z","caller":"traceutil/trace.go:171","msg":"trace[1721078764] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:671; }","duration":"580.409347ms","start":"2026-01-29T18:43:12.461864Z","end":"2026-01-29T18:43:13.042273Z","steps":["trace[1721078764] 'agreement among raft nodes before linearized reading' (duration: 580.384096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:13.042296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:12.461859Z","time spent":"580.432797ms","remote":"127.0.0.1:36318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-29T18:43:13.252576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.573955ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-01-29T18:43:13.253394Z","caller":"traceutil/trace.go:171","msg":"trace[75622058] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"101.201151ms","start":"2026-01-29T18:43:13.152181Z","end":"2026-01-29T18:43:13.253382Z","steps":["trace[75622058] 'read index received' (duration: 101.092139ms)","trace[75622058] 'applied index is now lower than readState.Index' (duration: 108.092µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:13.253605Z","caller":"traceutil/trace.go:171","msg":"trace[795565188] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"196.201468ms","start":"2026-01-29T18:43:13.057396Z","end":"2026-01-29T18:43:13.253598Z","steps":["trace[795565188] 'process raft request' (duration: 195.910442ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:13.253766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.576148ms","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-01-29T18:43:13.253798Z","caller":"traceutil/trace.go:171","msg":"trace[88035427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"101.634258ms","start":"2026-01-29T18:43:13.152144Z","end":"2026-01-29T18:43:13.253778Z","steps":["trace[88035427] 'agreement among raft nodes before linearized reading' (duration: 101.552287ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:13.253927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.409125ms","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-01-29T18:43:13.253946Z","caller":"traceutil/trace.go:171","msg":"trace[2079284332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"101.426745ms","start":"2026-01-29T18:43:13.152512Z","end":"2026-01-29T18:43:13.253939Z","steps":["trace[2079284332] 'agreement among raft nodes before linearized reading' (duration: 101.378694ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:13.531563Z","caller":"traceutil/trace.go:171","msg":"trace[1832700225] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"115.89456ms","start":"2026-01-29T18:43:13.415651Z","end":"2026-01-29T18:43:13.531546Z","steps":["trace[1832700225] 'process raft request' (duration: 82.051538ms)","trace[1832700225] 'compare' (duration: 33.572407ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:14.052502Z","caller":"traceutil/trace.go:171","msg":"trace[1847182399] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"141.796283ms","start":"2026-01-29T18:43:13.910689Z","end":"2026-01-29T18:43:14.052485Z","steps":["trace[1847182399] 'process raft request' (duration: 141.770082ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:14.052743Z","caller":"traceutil/trace.go:171","msg":"trace[1785035280] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"142.549054ms","start":"2026-01-29T18:43:13.910168Z","end":"2026-01-29T18:43:14.052717Z","steps":["trace[1785035280] 'process raft request' (duration: 109.926456ms)","trace[1785035280] 'compare' (duration: 32.279503ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:19.948012Z","caller":"traceutil/trace.go:171","msg":"trace[1927996755] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"110.847999ms","start":"2026-01-29T18:43:19.837149Z","end":"2026-01-29T18:43:19.947997Z","steps":["trace[1927996755] 'process raft request' (duration: 110.762078ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:22.371159Z","caller":"traceutil/trace.go:171","msg":"trace[569322932] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"191.151358ms","start":"2026-01-29T18:43:22.179986Z","end":"2026-01-29T18:43:22.371137Z","steps":["trace[569322932] 'process raft request' (duration: 191.029046ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:22.493032Z","caller":"traceutil/trace.go:171","msg":"trace[316595722] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"188.428205ms","start":"2026-01-29T18:43:22.30458Z","end":"2026-01-29T18:43:22.493008Z","steps":["trace[316595722] 'read index received' (duration: 67.101887ms)","trace[316595722] 'applied index is now lower than readState.Index' (duration: 121.325358ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:22.493363Z","caller":"traceutil/trace.go:171","msg":"trace[1938513825] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"306.350458ms","start":"2026-01-29T18:43:22.186999Z","end":"2026-01-29T18:43:22.49335Z","steps":["trace[1938513825] 'process raft request' (duration: 221.691869ms)","trace[1938513825] 'compare' (duration: 84.218012ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:22.493463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:22.186981Z","time spent":"306.415339ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:22.493711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.132617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:22.493743Z","caller":"traceutil/trace.go:171","msg":"trace[77937573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"189.189177ms","start":"2026-01-29T18:43:22.304544Z","end":"2026-01-29T18:43:22.493734Z","steps":["trace[77937573] 'agreement among raft nodes before linearized reading' (duration: 189.119176ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.146267Z","caller":"traceutil/trace.go:171","msg":"trace[1939058144] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"128.002554ms","start":"2026-01-29T18:43:23.018238Z","end":"2026-01-29T18:43:23.146241Z","steps":["trace[1939058144] 'process raft request' (duration: 66.429231ms)","trace[1939058144] 'compare' (duration: 61.482402ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:23.572036Z","caller":"traceutil/trace.go:171","msg":"trace[1141926170] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"108.810148ms","start":"2026-01-29T18:43:23.463207Z","end":"2026-01-29T18:43:23.572017Z","steps":["trace[1141926170] 'read index received' (duration: 108.627625ms)","trace[1141926170] 'applied index is now lower than readState.Index' (duration: 181.763µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:23.572125Z","caller":"traceutil/trace.go:171","msg":"trace[90541260] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"109.743912ms","start":"2026-01-29T18:43:23.462373Z","end":"2026-01-29T18:43:23.572117Z","steps":["trace[90541260] 'process raft request' (duration: 109.511389ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:23.572313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.550716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T18:43:23.572357Z","caller":"traceutil/trace.go:171","msg":"trace[234390245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"105.614257ms","start":"2026-01-29T18:43:23.466731Z","end":"2026-01-29T18:43:23.572346Z","steps":["trace[234390245] 'agreement among raft nodes before linearized reading' (duration: 105.492595ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:23.572499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.290255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T18:43:23.572512Z","caller":"traceutil/trace.go:171","msg":"trace[1032688417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"109.316825ms","start":"2026-01-29T18:43:23.463191Z","end":"2026-01-29T18:43:23.572508Z","steps":["trace[1032688417] 'agreement among raft nodes before linearized reading' (duration: 109.277265ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.694251Z","caller":"traceutil/trace.go:171","msg":"trace[254166526] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"107.558438ms","start":"2026-01-29T18:43:23.586676Z","end":"2026-01-29T18:43:23.694235Z","steps":["trace[254166526] 'process raft request' (duration: 107.418865ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.694411Z","caller":"traceutil/trace.go:171","msg":"trace[1663356721] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"105.251441ms","start":"2026-01-29T18:43:23.589115Z","end":"2026-01-29T18:43:23.694366Z","steps":["trace[1663356721] 'process raft request' (duration: 105.064297ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.694707Z","caller":"traceutil/trace.go:171","msg":"trace[378221341] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"107.610568ms","start":"2026-01-29T18:43:23.58709Z","end":"2026-01-29T18:43:23.694701Z","steps":["trace[378221341] 'read index received' (duration: 30.866703ms)","trace[378221341] 'applied index is now lower than readState.Index' (duration: 76.743355ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:23.694689Z","caller":"traceutil/trace.go:171","msg":"trace[510693485] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"105.263281ms","start":"2026-01-29T18:43:23.589405Z","end":"2026-01-29T18:43:23.694668Z","steps":["trace[510693485] 'process raft request' (duration: 104.800462ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:23.694776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.673319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:23.694794Z","caller":"traceutil/trace.go:171","msg":"trace[247686637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"107.71747ms","start":"2026-01-29T18:43:23.587071Z","end":"2026-01-29T18:43:23.694789Z","steps":["trace[247686637] 'agreement among raft nodes before linearized reading' (duration: 107.649749ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.694867Z","caller":"traceutil/trace.go:171","msg":"trace[930644573] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"104.482659ms","start":"2026-01-29T18:43:23.590379Z","end":"2026-01-29T18:43:23.694862Z","steps":["trace[930644573] 'process raft request' (duration: 104.215504ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.901149Z","caller":"traceutil/trace.go:171","msg":"trace[1948546608] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:786; }","duration":"145.619565ms","start":"2026-01-29T18:43:23.755507Z","end":"2026-01-29T18:43:23.901127Z","steps":["trace[1948546608] 'read index received' (duration: 66.776597ms)","trace[1948546608] 'applied index is now lower than readState.Index' (duration: 78.841848ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:23.901245Z","caller":"traceutil/trace.go:171","msg":"trace[1823591632] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"152.25359ms","start":"2026-01-29T18:43:23.748984Z","end":"2026-01-29T18:43:23.901238Z","steps":["trace[1823591632] 'process raft request' (duration: 151.928305ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.901458Z","caller":"traceutil/trace.go:171","msg":"trace[1975075094] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"146.318686ms","start":"2026-01-29T18:43:23.755133Z","end":"2026-01-29T18:43:23.901451Z","steps":["trace[1975075094] 'process raft request' (duration: 145.952951ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.901447Z","caller":"traceutil/trace.go:171","msg":"trace[2045399582] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"147.906131ms","start":"2026-01-29T18:43:23.753519Z","end":"2026-01-29T18:43:23.901426Z","steps":["trace[2045399582] 'process raft request' (duration: 147.515775ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:23.901703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.180755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-m7v5r\" ","response":"range_response_count:1 size:4696"} {"level":"info","ts":"2026-01-29T18:43:23.901732Z","caller":"traceutil/trace.go:171","msg":"trace[703970742] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-m7v5r; range_end:; response_count:1; response_revision:761; }","duration":"146.233866ms","start":"2026-01-29T18:43:23.755489Z","end":"2026-01-29T18:43:23.901723Z","steps":["trace[703970742] 'agreement among raft nodes before linearized reading' (duration: 146.127544ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.978684Z","caller":"traceutil/trace.go:171","msg":"trace[771644829] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"148.334059ms","start":"2026-01-29T18:43:23.830325Z","end":"2026-01-29T18:43:23.978659Z","steps":["trace[771644829] 'process raft request' (duration: 148.164976ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:23.978751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.57129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-m7v5r\" ","response":"range_response_count:1 size:4696"} {"level":"info","ts":"2026-01-29T18:43:23.978814Z","caller":"traceutil/trace.go:171","msg":"trace[533607497] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-m7v5r; range_end:; response_count:1; response_revision:763; }","duration":"146.658222ms","start":"2026-01-29T18:43:23.832137Z","end":"2026-01-29T18:43:23.978795Z","steps":["trace[533607497] 'agreement among raft nodes before linearized reading' (duration: 146.490649ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:23.979011Z","caller":"traceutil/trace.go:171","msg":"trace[941131308] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"146.810614ms","start":"2026-01-29T18:43:23.83219Z","end":"2026-01-29T18:43:23.979001Z","steps":["trace[941131308] 'process raft request' (duration: 146.398547ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.110043Z","caller":"traceutil/trace.go:171","msg":"trace[377096475] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"200.206388ms","start":"2026-01-29T18:43:23.909813Z","end":"2026-01-29T18:43:24.11002Z","steps":["trace[377096475] 'process raft request' (duration: 199.905703ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.194038Z","caller":"traceutil/trace.go:171","msg":"trace[395943310] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:791; }","duration":"205.393523ms","start":"2026-01-29T18:43:23.98863Z","end":"2026-01-29T18:43:24.194023Z","steps":["trace[395943310] 'read index received' (duration: 121.009133ms)","trace[395943310] 'applied index is now lower than readState.Index' (duration: 84.38386ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:43:24.194098Z","caller":"traceutil/trace.go:171","msg":"trace[1366678521] transaction","detail":"{read_only:false; number_of_response:1; response_revision:764; }","duration":"205.77736ms","start":"2026-01-29T18:43:23.988312Z","end":"2026-01-29T18:43:24.194089Z","steps":["trace[1366678521] 'process raft request' (duration: 205.643668ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.194317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.676857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-01-29T18:43:24.194334Z","caller":"traceutil/trace.go:171","msg":"trace[1746074055] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:764; }","duration":"205.726289ms","start":"2026-01-29T18:43:23.988602Z","end":"2026-01-29T18:43:24.194329Z","steps":["trace[1746074055] 'agreement among raft nodes before linearized reading' (duration: 205.629327ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.380365Z","caller":"traceutil/trace.go:171","msg":"trace[65872460] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"256.99458ms","start":"2026-01-29T18:43:24.123357Z","end":"2026-01-29T18:43:24.380352Z","steps":["trace[65872460] 'process raft request' (duration: 256.95595ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.380648Z","caller":"traceutil/trace.go:171","msg":"trace[1720593955] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"257.574811ms","start":"2026-01-29T18:43:24.123066Z","end":"2026-01-29T18:43:24.380641Z","steps":["trace[1720593955] 'process raft request' (duration: 257.157323ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.380909Z","caller":"traceutil/trace.go:171","msg":"trace[460383987] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:795; }","duration":"186.829607ms","start":"2026-01-29T18:43:24.194073Z","end":"2026-01-29T18:43:24.380902Z","steps":["trace[460383987] 'read index received' (duration: 186.826467ms)","trace[460383987] 'applied index is now lower than readState.Index' (duration: 2.49µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:24.38102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.59981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-m7v5r\" ","response":"range_response_count:1 size:4808"} {"level":"info","ts":"2026-01-29T18:43:24.381039Z","caller":"traceutil/trace.go:171","msg":"trace[498230745] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-m7v5r; range_end:; response_count:1; response_revision:766; }","duration":"257.645822ms","start":"2026-01-29T18:43:24.123388Z","end":"2026-01-29T18:43:24.381033Z","steps":["trace[498230745] 'agreement among raft nodes before linearized reading' (duration: 257.54802ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.38117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.460374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T18:43:24.381194Z","caller":"traceutil/trace.go:171","msg":"trace[1594128033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"331.483034ms","start":"2026-01-29T18:43:24.049703Z","end":"2026-01-29T18:43:24.381186Z","steps":["trace[1594128033] 'agreement among raft nodes before linearized reading' (duration: 331.418253ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.381216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:24.049664Z","time spent":"331.545636ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-29T18:43:24.403113Z","caller":"traceutil/trace.go:171","msg":"trace[1214184649] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"200.700937ms","start":"2026-01-29T18:43:24.202401Z","end":"2026-01-29T18:43:24.403102Z","steps":["trace[1214184649] 'process raft request' (duration: 200.670377ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.403335Z","caller":"traceutil/trace.go:171","msg":"trace[1571109726] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"207.974913ms","start":"2026-01-29T18:43:24.195353Z","end":"2026-01-29T18:43:24.403328Z","steps":["trace[1571109726] 'process raft request' (duration: 207.654458ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.905812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.10415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:43:24.905916Z","caller":"traceutil/trace.go:171","msg":"trace[1918115314] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"123.000824ms","start":"2026-01-29T18:43:24.782905Z","end":"2026-01-29T18:43:24.905906Z","steps":["trace[1918115314] 'read index received' (duration: 27.391µs)","trace[1918115314] 'applied index is now lower than readState.Index' (duration: 122.972883ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:24.905963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.062514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:43:24.905978Z","caller":"traceutil/trace.go:171","msg":"trace[1721087493] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:777; }","duration":"123.101845ms","start":"2026-01-29T18:43:24.782871Z","end":"2026-01-29T18:43:24.905973Z","steps":["trace[1721087493] 'agreement among raft nodes before linearized reading' (duration: 123.060734ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:24.906151Z","caller":"traceutil/trace.go:171","msg":"trace[399633317] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"395.059793ms","start":"2026-01-29T18:43:24.511085Z","end":"2026-01-29T18:43:24.906145Z","steps":["trace[399633317] 'process raft request' (duration: 105.574437ms)","trace[399633317] 'compare' (duration: 288.805796ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:24.906179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:24.511073Z","time spent":"395.091154ms","remote":"127.0.0.1:36466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T18:43:24.906328Z","caller":"traceutil/trace.go:171","msg":"trace[1969986077] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"393.491518ms","start":"2026-01-29T18:43:24.512831Z","end":"2026-01-29T18:43:24.906322Z","steps":["trace[1969986077] 'process raft request' (duration: 393.045061ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:24.906353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:24.512823Z","time spent":"393.516058ms","remote":"127.0.0.1:36266","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-01-29T18:43:26.591212Z","caller":"traceutil/trace.go:171","msg":"trace[1550626157] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"157.042217ms","start":"2026-01-29T18:43:26.434158Z","end":"2026-01-29T18:43:26.5912Z","steps":["trace[1550626157] 'process raft request' (duration: 156.947166ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:26.591451Z","caller":"traceutil/trace.go:171","msg":"trace[1837952263] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:812; }","duration":"121.981247ms","start":"2026-01-29T18:43:26.469465Z","end":"2026-01-29T18:43:26.591446Z","steps":["trace[1837952263] 'read index received' (duration: 121.978837ms)","trace[1837952263] 'applied index is now lower than readState.Index' (duration: 1.99µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:26.591557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.083228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:43:26.591577Z","caller":"traceutil/trace.go:171","msg":"trace[1772055977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"122.140159ms","start":"2026-01-29T18:43:26.469432Z","end":"2026-01-29T18:43:26.591572Z","steps":["trace[1772055977] 'agreement among raft nodes before linearized reading' (duration: 122.049217ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:26.593179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.103789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-grcd2\" ","response":"range_response_count:1 size:3795"} {"level":"info","ts":"2026-01-29T18:43:26.593304Z","caller":"traceutil/trace.go:171","msg":"trace[1084864327] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-grcd2; range_end:; response_count:1; response_revision:784; }","duration":"117.245091ms","start":"2026-01-29T18:43:26.476034Z","end":"2026-01-29T18:43:26.593279Z","steps":["trace[1084864327] 'agreement among raft nodes before linearized reading' (duration: 116.981227ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:26.593231Z","caller":"traceutil/trace.go:171","msg":"trace[1322977389] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"117.663798ms","start":"2026-01-29T18:43:26.475545Z","end":"2026-01-29T18:43:26.593209Z","steps":["trace[1322977389] 'process raft request' (duration: 117.324683ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:30.797821Z","caller":"traceutil/trace.go:171","msg":"trace[1917156630] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"112.701835ms","start":"2026-01-29T18:43:30.685102Z","end":"2026-01-29T18:43:30.797804Z","steps":["trace[1917156630] 'read index received' (duration: 112.557883ms)","trace[1917156630] 'applied index is now lower than readState.Index' (duration: 143.292µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:30.797976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.856797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:43:30.797998Z","caller":"traceutil/trace.go:171","msg":"trace[187765427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"112.927919ms","start":"2026-01-29T18:43:30.685063Z","end":"2026-01-29T18:43:30.797991Z","steps":["trace[187765427] 'agreement among raft nodes before linearized reading' (duration: 112.806417ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:30.798075Z","caller":"traceutil/trace.go:171","msg":"trace[1401229472] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"149.44631ms","start":"2026-01-29T18:43:30.648608Z","end":"2026-01-29T18:43:30.798054Z","steps":["trace[1401229472] 'process raft request' (duration: 149.098324ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:38.052538Z","caller":"traceutil/trace.go:171","msg":"trace[365391195] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"208.58692ms","start":"2026-01-29T18:43:37.84393Z","end":"2026-01-29T18:43:38.052517Z","steps":["trace[365391195] 'process raft request' (duration: 208.462518ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:43.671471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.937197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:43:43.674029Z","caller":"traceutil/trace.go:171","msg":"trace[1283771083] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"654.012939ms","start":"2026-01-29T18:43:43.017786Z","end":"2026-01-29T18:43:43.671799Z","steps":["trace[1283771083] 'process raft request' (duration: 35.665647ms)","trace[1283771083] 'compare' (duration: 617.756944ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:43.674111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:43.017774Z","time spent":"656.290642ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:44.003743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.993246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T18:43:44.003787Z","caller":"traceutil/trace.go:171","msg":"trace[245182035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:841; }","duration":"113.068547ms","start":"2026-01-29T18:43:43.890708Z","end":"2026-01-29T18:43:44.003777Z","steps":["trace[245182035] 'range keys from in-memory index tree' (duration: 112.899224ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:44.152024Z","caller":"traceutil/trace.go:171","msg":"trace[1008441185] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"140.713888ms","start":"2026-01-29T18:43:44.011271Z","end":"2026-01-29T18:43:44.151984Z","steps":["trace[1008441185] 'process raft request' (duration: 140.202461ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:44.29658Z","caller":"traceutil/trace.go:171","msg":"trace[569749419] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"140.155501ms","start":"2026-01-29T18:43:44.156396Z","end":"2026-01-29T18:43:44.296552Z","steps":["trace[569749419] 'process raft request' (duration: 90.532475ms)","trace[569749419] 'compare' (duration: 49.526024ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:46.459694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.564317ms","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-01-29T18:43:46.459766Z","caller":"traceutil/trace.go:171","msg":"trace[953640568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:847; }","duration":"156.66932ms","start":"2026-01-29T18:43:46.303076Z","end":"2026-01-29T18:43:46.459745Z","steps":["trace[953640568] 'range keys from in-memory index tree' (duration: 156.424986ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:47.05218Z","caller":"traceutil/trace.go:171","msg":"trace[2097995651] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"169.061075ms","start":"2026-01-29T18:43:46.883102Z","end":"2026-01-29T18:43:47.052163Z","steps":["trace[2097995651] 'process raft request' (duration: 168.965603ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:43:47.26901Z","caller":"traceutil/trace.go:171","msg":"trace[1461977778] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"314.022912ms","start":"2026-01-29T18:43:46.954971Z","end":"2026-01-29T18:43:47.268994Z","steps":["trace[1461977778] 'read index received' (duration: 97.876976ms)","trace[1461977778] 'applied index is now lower than readState.Index' (duration: 216.145456ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:47.269139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.159004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:43:47.269158Z","caller":"traceutil/trace.go:171","msg":"trace[1553894075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"314.219276ms","start":"2026-01-29T18:43:46.954931Z","end":"2026-01-29T18:43:47.26915Z","steps":["trace[1553894075] 'agreement among raft nodes before linearized reading' (duration: 314.120004ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:43:47.269175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:46.954911Z","time spent":"314.260316ms","remote":"127.0.0.1:36266","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-01-29T18:43:47.269277Z","caller":"traceutil/trace.go:171","msg":"trace[2059260060] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"372.137333ms","start":"2026-01-29T18:43:46.897133Z","end":"2026-01-29T18:43:47.26927Z","steps":["trace[2059260060] 'process raft request' (duration: 355.02165ms)","trace[2059260060] 'compare' (duration: 16.534825ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:43:47.26932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:43:46.897121Z","time spent":"372.168093ms","remote":"127.0.0.1:36184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:43:48.370029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.717297ms","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-01-29T18:43:48.370098Z","caller":"traceutil/trace.go:171","msg":"trace[2079865172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:853; }","duration":"186.838159ms","start":"2026-01-29T18:43:48.183245Z","end":"2026-01-29T18:43:48.370083Z","steps":["trace[2079865172] 'range keys from in-memory index tree' (duration: 186.505145ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:17.363905Z","caller":"traceutil/trace.go:171","msg":"trace[119169169] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"126.850505ms","start":"2026-01-29T18:44:17.237034Z","end":"2026-01-29T18:44:17.363884Z","steps":["trace[119169169] 'process raft request' (duration: 126.745963ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:21.089422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.139528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T18:44:21.08951Z","caller":"traceutil/trace.go:171","msg":"trace[1730727359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"244.27976ms","start":"2026-01-29T18:44:20.845208Z","end":"2026-01-29T18:44:21.089487Z","steps":["trace[1730727359] 'range keys from in-memory index tree' (duration: 243.950385ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:22.607398Z","caller":"traceutil/trace.go:171","msg":"trace[1504610442] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"153.089994ms","start":"2026-01-29T18:44:22.454291Z","end":"2026-01-29T18:44:22.60738Z","steps":["trace[1504610442] 'read index received' (duration: 152.953192ms)","trace[1504610442] 'applied index is now lower than readState.Index' (duration: 135.862µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:22.607606Z","caller":"traceutil/trace.go:171","msg":"trace[861371486] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"447.851219ms","start":"2026-01-29T18:44:22.159747Z","end":"2026-01-29T18:44:22.607598Z","steps":["trace[861371486] 'process raft request' (duration: 447.542915ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:22.607681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:44:22.159732Z","time spent":"447.894429ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T18:44:22.6079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.61153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T18:44:22.607922Z","caller":"traceutil/trace.go:171","msg":"trace[146357544] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:980; }","duration":"153.656932ms","start":"2026-01-29T18:44:22.454259Z","end":"2026-01-29T18:44:22.607916Z","steps":["trace[146357544] 'agreement among raft nodes before linearized reading' (duration: 153.52955ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:22.608039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.079832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T18:44:22.608059Z","caller":"traceutil/trace.go:171","msg":"trace[248257931] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:980; }","duration":"118.127702ms","start":"2026-01-29T18:44:22.489924Z","end":"2026-01-29T18:44:22.608052Z","steps":["trace[248257931] 'agreement among raft nodes before linearized reading' (duration: 118.092572ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:22.891856Z","caller":"traceutil/trace.go:171","msg":"trace[1391547588] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"151.301592ms","start":"2026-01-29T18:44:22.740534Z","end":"2026-01-29T18:44:22.891836Z","steps":["trace[1391547588] 'process raft request' (duration: 139.768766ms)","trace[1391547588] 'compare' (duration: 11.170181ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:24.739694Z","caller":"traceutil/trace.go:171","msg":"trace[114184757] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"124.268991ms","start":"2026-01-29T18:44:24.615396Z","end":"2026-01-29T18:44:24.739665Z","steps":["trace[114184757] 'process raft request' (duration: 124.099499ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:29.243845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.810121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-01-29T18:44:29.24462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.445566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:29.244673Z","caller":"traceutil/trace.go:171","msg":"trace[1872609202] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1003; }","duration":"129.535667ms","start":"2026-01-29T18:44:29.115125Z","end":"2026-01-29T18:44:29.24466Z","steps":["trace[1872609202] 'count revisions from in-memory index tree' (duration: 129.373595ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:29.243918Z","caller":"traceutil/trace.go:171","msg":"trace[1774652938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"245.922312ms","start":"2026-01-29T18:44:28.997978Z","end":"2026-01-29T18:44:29.2439Z","steps":["trace[1774652938] 'range keys from in-memory index tree' (duration: 245.603258ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:30.201588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.995822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-29T18:44:30.213298Z","caller":"traceutil/trace.go:171","msg":"trace[1005534628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1005; }","duration":"766.743937ms","start":"2026-01-29T18:44:29.446537Z","end":"2026-01-29T18:44:30.213281Z","steps":["trace[1005534628] 'range keys from in-memory index tree' (duration: 754.82539ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:30.213732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:44:29.446494Z","time spent":"766.96903ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T18:44:30.2139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.153453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:44:30.214882Z","caller":"traceutil/trace.go:171","msg":"trace[1839642072] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"630.199473ms","start":"2026-01-29T18:44:29.584672Z","end":"2026-01-29T18:44:30.214871Z","steps":["trace[1839642072] 'read index received' (duration: 50.691µs)","trace[1839642072] 'applied index is now lower than readState.Index' (duration: 630.147512ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:30.215005Z","caller":"traceutil/trace.go:171","msg":"trace[33154004] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"689.857262ms","start":"2026-01-29T18:44:29.525138Z","end":"2026-01-29T18:44:30.214996Z","steps":["trace[33154004] 'process raft request' (duration: 16.537735ms)","trace[33154004] 'compare' (duration: 671.112781ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:44:30.215109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:44:29.52512Z","time spent":"689.960764ms","remote":"127.0.0.1:36266","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-01-29T18:44:30.215311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.640499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:30.215361Z","caller":"traceutil/trace.go:171","msg":"trace[912608776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"630.68102ms","start":"2026-01-29T18:44:29.584663Z","end":"2026-01-29T18:44:30.215344Z","steps":["trace[912608776] 'agreement among raft nodes before linearized reading' (duration: 630.622309ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:30.215391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:44:29.584613Z","time spent":"630.77339ms","remote":"127.0.0.1:36016","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-01-29T18:44:30.215684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.02967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T18:44:30.215719Z","caller":"traceutil/trace.go:171","msg":"trace[391800052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"484.09881ms","start":"2026-01-29T18:44:29.731615Z","end":"2026-01-29T18:44:30.215714Z","steps":["trace[391800052] 'agreement among raft nodes before linearized reading' (duration: 483.874977ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:30.215759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:44:29.731596Z","time spent":"484.157751ms","remote":"127.0.0.1:36266","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-01-29T18:44:36.423984Z","caller":"traceutil/trace.go:171","msg":"trace[568634355] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"143.089616ms","start":"2026-01-29T18:44:36.28087Z","end":"2026-01-29T18:44:36.42396Z","steps":["trace[568634355] 'process raft request' (duration: 132.279584ms)","trace[568634355] 'compare' (duration: 10.450117ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:45.105882Z","caller":"traceutil/trace.go:171","msg":"trace[36742388] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"203.505689ms","start":"2026-01-29T18:44:44.902349Z","end":"2026-01-29T18:44:45.105855Z","steps":["trace[36742388] 'process raft request' (duration: 203.361318ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:45.106644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.150954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-01-29T18:44:45.10672Z","caller":"traceutil/trace.go:171","msg":"trace[2065016869] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1052; }","duration":"173.259015ms","start":"2026-01-29T18:44:44.933447Z","end":"2026-01-29T18:44:45.106706Z","steps":["trace[2065016869] 'agreement among raft nodes before linearized reading' (duration: 173.138804ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:45.106538Z","caller":"traceutil/trace.go:171","msg":"trace[275620521] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1098; }","duration":"173.048943ms","start":"2026-01-29T18:44:44.933473Z","end":"2026-01-29T18:44:45.106522Z","steps":["trace[275620521] 'read index received' (duration: 172.167443ms)","trace[275620521] 'applied index is now lower than readState.Index' (duration: 880.17µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:44:45.107164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.980305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-01-29T18:44:45.107242Z","caller":"traceutil/trace.go:171","msg":"trace[1447023388] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:1052; }","duration":"119.095718ms","start":"2026-01-29T18:44:44.988134Z","end":"2026-01-29T18:44:45.10723Z","steps":["trace[1447023388] 'agreement among raft nodes before linearized reading' (duration: 118.947626ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:48.024467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.601059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/default/\" range_end:\"/registry/cronjobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:48.02452Z","caller":"traceutil/trace.go:171","msg":"trace[130818705] range","detail":"{range_begin:/registry/cronjobs/default/; range_end:/registry/cronjobs/default0; response_count:0; response_revision:1060; }","duration":"110.688139ms","start":"2026-01-29T18:44:47.913822Z","end":"2026-01-29T18:44:48.02451Z","steps":["trace[130818705] 'agreement among raft nodes before linearized reading' (duration: 110.615739ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:48.722266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.202444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-01-29T18:44:48.722363Z","caller":"traceutil/trace.go:171","msg":"trace[640569296] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1062; }","duration":"101.336937ms","start":"2026-01-29T18:44:48.62101Z","end":"2026-01-29T18:44:48.722347Z","steps":["trace[640569296] 'range keys from in-memory index tree' (duration: 101.105554ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:49.32659Z","caller":"traceutil/trace.go:171","msg":"trace[502338991] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"181.239474ms","start":"2026-01-29T18:44:49.145291Z","end":"2026-01-29T18:44:49.326531Z","steps":["trace[502338991] 'read index received' (duration: 181.091142ms)","trace[502338991] 'applied index is now lower than readState.Index' (duration: 147.492µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:49.326861Z","caller":"traceutil/trace.go:171","msg":"trace[410016960] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"200.656214ms","start":"2026-01-29T18:44:49.126187Z","end":"2026-01-29T18:44:49.326843Z","steps":["trace[410016960] 'process raft request' (duration: 200.23276ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:49.327439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.462709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:49.327546Z","caller":"traceutil/trace.go:171","msg":"trace[2001355458] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:1063; }","duration":"161.53264ms","start":"2026-01-29T18:44:49.16594Z","end":"2026-01-29T18:44:49.327472Z","steps":["trace[2001355458] 'agreement among raft nodes before linearized reading' (duration: 161.419028ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:49.327841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.711295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:49.327879Z","caller":"traceutil/trace.go:171","msg":"trace[1386483784] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:1063; }","duration":"136.783445ms","start":"2026-01-29T18:44:49.191086Z","end":"2026-01-29T18:44:49.32787Z","steps":["trace[1386483784] 'agreement among raft nodes before linearized reading' (duration: 136.724085ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:49.327006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.699969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-01-29T18:44:49.333585Z","caller":"traceutil/trace.go:171","msg":"trace[1726251288] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1063; }","duration":"183.921245ms","start":"2026-01-29T18:44:49.145259Z","end":"2026-01-29T18:44:49.329181Z","steps":["trace[1726251288] 'agreement among raft nodes before linearized reading' (duration: 181.641008ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T18:44:49.551694Z","caller":"traceutil/trace.go:171","msg":"trace[407042239] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"119.278747ms","start":"2026-01-29T18:44:49.432396Z","end":"2026-01-29T18:44:49.551674Z","steps":["trace[407042239] 'read index received' (duration: 44.467607ms)","trace[407042239] 'applied index is now lower than readState.Index' (duration: 74.81025ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:49.55262Z","caller":"traceutil/trace.go:171","msg":"trace[1322400255] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"127.595356ms","start":"2026-01-29T18:44:49.424885Z","end":"2026-01-29T18:44:49.552481Z","steps":["trace[1322400255] 'process raft request' (duration: 52.054908ms)","trace[1322400255] 'compare' (duration: 74.657538ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:44:49.553636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.22107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-01-29T18:44:49.553728Z","caller":"traceutil/trace.go:171","msg":"trace[1093887401] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1064; }","duration":"121.341821ms","start":"2026-01-29T18:44:49.432374Z","end":"2026-01-29T18:44:49.553715Z","steps":["trace[1093887401] 'agreement among raft nodes before linearized reading' (duration: 120.677944ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:49.553969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.477281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-node-lease/\" range_end:\"/registry/roles/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:49.554042Z","caller":"traceutil/trace.go:171","msg":"trace[2073118379] range","detail":"{range_begin:/registry/roles/kube-node-lease/; range_end:/registry/roles/kube-node-lease0; response_count:0; response_revision:1064; }","duration":"119.578292ms","start":"2026-01-29T18:44:49.434448Z","end":"2026-01-29T18:44:49.554027Z","steps":["trace[2073118379] 'agreement among raft nodes before linearized reading' (duration: 119.42823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:49.554358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.383729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:49.554387Z","caller":"traceutil/trace.go:171","msg":"trace[2042028442] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1064; }","duration":"108.429399ms","start":"2026-01-29T18:44:49.44595Z","end":"2026-01-29T18:44:49.55438Z","steps":["trace[2042028442] 'agreement among raft nodes before linearized reading' (duration: 108.384368ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:49.554673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.948613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T18:44:49.5547Z","caller":"traceutil/trace.go:171","msg":"trace[2051376330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"113.988023ms","start":"2026-01-29T18:44:49.440705Z","end":"2026-01-29T18:44:49.554694Z","steps":["trace[2051376330] 'agreement among raft nodes before linearized reading' (duration: 113.874532ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:49.783647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.3997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T18:44:49.784045Z","caller":"traceutil/trace.go:171","msg":"trace[352226357] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"132.585785ms","start":"2026-01-29T18:44:49.651438Z","end":"2026-01-29T18:44:49.784024Z","steps":["trace[352226357] 'read index received' (duration: 6.71137ms)","trace[352226357] 'applied index is now lower than readState.Index' (duration: 125.873315ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T18:44:49.784119Z","caller":"traceutil/trace.go:171","msg":"trace[1621943856] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"158.736016ms","start":"2026-01-29T18:44:49.625374Z","end":"2026-01-29T18:44:49.78411Z","steps":["trace[1621943856] 'process raft request' (duration: 32.80848ms)","trace[1621943856] 'compare' (duration: 125.202928ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T18:44:49.784283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.841068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/\" range_end:\"/registry/serviceaccounts/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-01-29T18:44:49.784361Z","caller":"traceutil/trace.go:171","msg":"trace[780748555] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/; range_end:/registry/serviceaccounts/kube-node-lease0; response_count:1; response_revision:1066; }","duration":"132.945889ms","start":"2026-01-29T18:44:49.651408Z","end":"2026-01-29T18:44:49.784354Z","steps":["trace[780748555] 'agreement among raft nodes before linearized reading' (duration: 132.846558ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T18:44:49.784512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.810348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-node-lease/\" range_end:\"/registry/services/specs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T18:44:49.784567Z","caller":"traceutil/trace.go:171","msg":"trace[2141596479] range","detail":"{range_begin:/registry/services/specs/kube-node-lease/; range_end:/registry/services/specs/kube-node-lease0; response_count:0; response_revision:1066; }","duration":"127.878078ms","start":"2026-01-29T18:44:49.656684Z","end":"2026-01-29T18:44:49.784562Z","steps":["trace[2141596479] 'agreement among raft nodes before linearized reading' (duration: 127.811678ms)"],"step_count":1}