{"level":"warn","ts":"2026-01-19T13:43:53.67014Z","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-19T13:43:53.670445Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-19T13:43:53.670579Z","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-19T13:43:53.670656Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-01-19T13:43:53.670694Z","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-19T13:43:53.672809Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-01-19T13:43:53.6736Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-19T13:43:53.678927Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.440121ms"} {"level":"info","ts":"2026-01-19T13:43:53.688119Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-01-19T13:43:53.688188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-01-19T13:43:53.688224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-01-19T13:43:53.688249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-19T13:43:53.688274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-01-19T13:43:53.688317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-01-19T13:43:53.701874Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-19T13:43:53.703771Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-19T13:43:53.709901Z","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-19T13:43:53.714622Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-19T13:43:53.718769Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-19T13:43:53.718944Z","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-19T13:43:53.718992Z","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-19T13:43:53.719038Z","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-19T13:43:53.719625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-01-19T13:43:53.719853Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-01-19T13:43:53.720976Z","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-19T13:43:53.72113Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-01-19T13:43:53.721166Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-01-19T13:43:53.721404Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-19T13:43:53.721546Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-19T13:43:54.69037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-01-19T13:43:54.690523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-19T13:43:54.690596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-01-19T13:43:54.690659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-01-19T13:43:54.690711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-01-19T13:43:54.690766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-01-19T13:43:54.690806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-01-19T13:43:54.697061Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T13:43:54.699813Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T13:43:54.699895Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T13:43:54.699918Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T13:43:54.699958Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-01-19T13:43:54.700023Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T13:43:54.700174Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T13:43:54.70797Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-01-19T13:43:54.718862Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-19T13:43:54.718878Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-19T13:43:54.719062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-19T13:44:05.122804Z","caller":"traceutil/trace.go:171","msg":"trace[399040824] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"132.137272ms","start":"2026-01-19T13:44:04.990638Z","end":"2026-01-19T13:44:05.122776Z","steps":["trace[399040824] 'process raft request' (duration: 132.004439ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:05.125041Z","caller":"traceutil/trace.go:171","msg":"trace[1706471407] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:329; }","duration":"131.082828ms","start":"2026-01-19T13:44:04.99394Z","end":"2026-01-19T13:44:05.125023Z","steps":["trace[1706471407] 'read index received' (duration: 128.621592ms)","trace[1706471407] 'applied index is now lower than readState.Index' (duration: 2.459446ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:44:05.125176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.21363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-01-19T13:44:05.125221Z","caller":"traceutil/trace.go:171","msg":"trace[593816888] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:321; }","duration":"131.303753ms","start":"2026-01-19T13:44:04.993908Z","end":"2026-01-19T13:44:05.125212Z","steps":["trace[593816888] 'agreement among raft nodes before linearized reading' (duration: 131.208981ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:11.238952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.146685ms","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-19T13:44:11.239591Z","caller":"traceutil/trace.go:171","msg":"trace[1877983665] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:381; }","duration":"291.826729ms","start":"2026-01-19T13:44:10.94774Z","end":"2026-01-19T13:44:11.239567Z","steps":["trace[1877983665] 'range keys from in-memory index tree' (duration: 291.051032ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:11.909404Z","caller":"traceutil/trace.go:171","msg":"trace[611312487] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"653.956486ms","start":"2026-01-19T13:44:11.255422Z","end":"2026-01-19T13:44:11.909379Z","steps":["trace[611312487] 'process raft request' (duration: 653.814483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:11.91001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:11.255414Z","time spent":"654.062728ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:12.001276Z","caller":"traceutil/trace.go:171","msg":"trace[1954722928] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"741.272269ms","start":"2026-01-19T13:44:11.259985Z","end":"2026-01-19T13:44:12.001257Z","steps":["trace[1954722928] 'process raft request' (duration: 741.249678ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:12.001379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:11.259977Z","time spent":"741.34623ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:12.001444Z","caller":"traceutil/trace.go:171","msg":"trace[1416786957] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"742.950027ms","start":"2026-01-19T13:44:11.25849Z","end":"2026-01-19T13:44:12.00144Z","steps":["trace[1416786957] 'process raft request' (duration: 742.65413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:12.001465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:11.25847Z","time spent":"742.982897ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:16.639694Z","caller":"traceutil/trace.go:171","msg":"trace[374460545] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"164.604789ms","start":"2026-01-19T13:44:16.475074Z","end":"2026-01-19T13:44:16.639679Z","steps":["trace[374460545] 'process raft request' (duration: 164.535147ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:27.278863Z","caller":"traceutil/trace.go:171","msg":"trace[2079160541] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"113.485089ms","start":"2026-01-19T13:44:27.165366Z","end":"2026-01-19T13:44:27.278851Z","steps":["trace[2079160541] 'process raft request' (duration: 113.222073ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:34.343571Z","caller":"traceutil/trace.go:171","msg":"trace[355255927] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"227.630031ms","start":"2026-01-19T13:44:34.11592Z","end":"2026-01-19T13:44:34.34355Z","steps":["trace[355255927] 'read index received' (duration: 227.466767ms)","trace[355255927] 'applied index is now lower than readState.Index' (duration: 162.514µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:44:34.343705Z","caller":"traceutil/trace.go:171","msg":"trace[1641167190] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"227.909857ms","start":"2026-01-19T13:44:34.115785Z","end":"2026-01-19T13:44:34.343695Z","steps":["trace[1641167190] 'process raft request' (duration: 227.621281ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:34.344007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.068951ms","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-19T13:44:34.34405Z","caller":"traceutil/trace.go:171","msg":"trace[1825775360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"228.130452ms","start":"2026-01-19T13:44:34.115906Z","end":"2026-01-19T13:44:34.344037Z","steps":["trace[1825775360] 'agreement among raft nodes before linearized reading' (duration: 228.006579ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:34.642195Z","caller":"traceutil/trace.go:171","msg":"trace[110439120] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"291.587534ms","start":"2026-01-19T13:44:34.35059Z","end":"2026-01-19T13:44:34.642177Z","steps":["trace[110439120] 'process raft request' (duration: 291.42844ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:34.689743Z","caller":"traceutil/trace.go:171","msg":"trace[52628785] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"330.238361ms","start":"2026-01-19T13:44:34.359485Z","end":"2026-01-19T13:44:34.689723Z","steps":["trace[52628785] 'process raft request' (duration: 330.163999ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:34.690213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:34.359477Z","time spent":"330.656481ms","remote":"127.0.0.1:58734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:34.690782Z","caller":"traceutil/trace.go:171","msg":"trace[351966717] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"336.176566ms","start":"2026-01-19T13:44:34.354592Z","end":"2026-01-19T13:44:34.690769Z","steps":["trace[351966717] 'process raft request' (duration: 334.932678ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:34.691032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:34.354585Z","time spent":"336.406872ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:37.689603Z","caller":"traceutil/trace.go:171","msg":"trace[490313035] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"234.746993ms","start":"2026-01-19T13:44:37.45478Z","end":"2026-01-19T13:44:37.689527Z","steps":["trace[490313035] 'process raft request' (duration: 213.255065ms)","trace[490313035] 'compare' (duration: 16.900634ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:44:37.689939Z","caller":"traceutil/trace.go:171","msg":"trace[1653414598] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"113.1591ms","start":"2026-01-19T13:44:37.576772Z","end":"2026-01-19T13:44:37.689931Z","steps":["trace[1653414598] 'read index received' (duration: 91.272894ms)","trace[1653414598] 'applied index is now lower than readState.Index' (duration: 21.885216ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:44:37.690077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.322734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-19T13:44:37.690102Z","caller":"traceutil/trace.go:171","msg":"trace[490835835] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:544; }","duration":"113.400006ms","start":"2026-01-19T13:44:37.576695Z","end":"2026-01-19T13:44:37.690095Z","steps":["trace[490835835] 'agreement among raft nodes before linearized reading' (duration: 113.267443ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:39.582796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.782624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-19T13:44:39.582877Z","caller":"traceutil/trace.go:171","msg":"trace[605351329] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"131.910576ms","start":"2026-01-19T13:44:39.450946Z","end":"2026-01-19T13:44:39.582856Z","steps":["trace[605351329] 'range keys from in-memory index tree' (duration: 131.529898ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:45.509342Z","caller":"traceutil/trace.go:171","msg":"trace[1208680680] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"267.268712ms","start":"2026-01-19T13:44:45.242053Z","end":"2026-01-19T13:44:45.509322Z","steps":["trace[1208680680] 'process raft request' (duration: 267.109368ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:45.510096Z","caller":"traceutil/trace.go:171","msg":"trace[1122055339] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"107.304467ms","start":"2026-01-19T13:44:45.402751Z","end":"2026-01-19T13:44:45.510056Z","steps":["trace[1122055339] 'read index received' (duration: 106.343906ms)","trace[1122055339] 'applied index is now lower than readState.Index' (duration: 959.451µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:44:45.510675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.905231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T13:44:45.5109Z","caller":"traceutil/trace.go:171","msg":"trace[710897440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:625; }","duration":"108.161026ms","start":"2026-01-19T13:44:45.40273Z","end":"2026-01-19T13:44:45.510891Z","steps":["trace[710897440] 'agreement among raft nodes before linearized reading' (duration: 107.395939ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:48.764983Z","caller":"traceutil/trace.go:171","msg":"trace[815985648] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"105.297882ms","start":"2026-01-19T13:44:48.659657Z","end":"2026-01-19T13:44:48.764954Z","steps":["trace[815985648] 'process raft request' (duration: 105.031196ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:49.094158Z","caller":"traceutil/trace.go:171","msg":"trace[478070268] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"378.474008ms","start":"2026-01-19T13:44:48.715667Z","end":"2026-01-19T13:44:49.094141Z","steps":["trace[478070268] 'read index received' (duration: 49.380692ms)","trace[478070268] 'applied index is now lower than readState.Index' (duration: 329.092376ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:44:49.0946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.501285ms","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-19T13:44:49.094644Z","caller":"traceutil/trace.go:171","msg":"trace[1562966615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:677; }","duration":"200.569197ms","start":"2026-01-19T13:44:48.894067Z","end":"2026-01-19T13:44:49.094636Z","steps":["trace[1562966615] 'agreement among raft nodes before linearized reading' (duration: 200.497494ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:49.094804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.373117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-19T13:44:49.094829Z","caller":"traceutil/trace.go:171","msg":"trace[352945021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:677; }","duration":"180.408599ms","start":"2026-01-19T13:44:48.914414Z","end":"2026-01-19T13:44:49.094823Z","steps":["trace[352945021] 'agreement among raft nodes before linearized reading' (duration: 180.354018ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:49.095062Z","caller":"traceutil/trace.go:171","msg":"trace[1921257732] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"435.239198ms","start":"2026-01-19T13:44:48.659815Z","end":"2026-01-19T13:44:49.095054Z","steps":["trace[1921257732] 'process raft request' (duration: 434.195104ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:49.095125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:48.659811Z","time spent":"435.275579ms","remote":"127.0.0.1:58974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T13:44:49.096202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.858156ms","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-19T13:44:49.096237Z","caller":"traceutil/trace.go:171","msg":"trace[141408276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"380.564055ms","start":"2026-01-19T13:44:48.715663Z","end":"2026-01-19T13:44:49.096227Z","steps":["trace[141408276] 'agreement among raft nodes before linearized reading' (duration: 378.619411ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:49.096367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:48.715649Z","time spent":"380.709728ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T13:44:49.661177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085082811588001,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T13:44:50.090967Z","caller":"traceutil/trace.go:171","msg":"trace[843842694] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"991.313009ms","start":"2026-01-19T13:44:49.099635Z","end":"2026-01-19T13:44:50.090948Z","steps":["trace[843842694] 'process raft request' (duration: 991.197176ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:50.091233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:49.099622Z","time spent":"991.539955ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:50.265406Z","caller":"traceutil/trace.go:171","msg":"trace[459762040] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"1.164678856s","start":"2026-01-19T13:44:49.1007Z","end":"2026-01-19T13:44:50.265378Z","steps":["trace[459762040] 'process raft request' (duration: 1.141671324s)","trace[459762040] 'compare' (duration: 22.864599ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:44:50.265436Z","caller":"traceutil/trace.go:171","msg":"trace[972892041] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:698; }","duration":"1.104913229s","start":"2026-01-19T13:44:49.160504Z","end":"2026-01-19T13:44:50.265418Z","steps":["trace[972892041] 'read index received' (duration: 930.503217ms)","trace[972892041] 'applied index is now lower than readState.Index' (duration: 174.409172ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:44:50.265534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:49.100671Z","time spent":"1.164801559s","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:50.265692Z","caller":"traceutil/trace.go:171","msg":"trace[689496512] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.162067498s","start":"2026-01-19T13:44:49.103617Z","end":"2026-01-19T13:44:50.265685Z","steps":["trace[689496512] 'process raft request' (duration: 1.161695789s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:50.265767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:49.103608Z","time spent":"1.162105249s","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:50.265911Z","caller":"traceutil/trace.go:171","msg":"trace[1152452394] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.162008446s","start":"2026-01-19T13:44:49.103874Z","end":"2026-01-19T13:44:50.265882Z","steps":["trace[1152452394] 'process raft request' (duration: 1.161475503s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:50.265946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:49.103858Z","time spent":"1.162068368s","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T13:44:50.266043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.171117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T13:44:50.266083Z","caller":"traceutil/trace.go:171","msg":"trace[1452066205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"656.244978ms","start":"2026-01-19T13:44:49.609826Z","end":"2026-01-19T13:44:50.266071Z","steps":["trace[1452066205] 'agreement among raft nodes before linearized reading' (duration: 656.128675ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:50.266123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:49.609806Z","time spent":"656.306139ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-19T13:44:50.266266Z","caller":"traceutil/trace.go:171","msg":"trace[1418267162] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"222.906415ms","start":"2026-01-19T13:44:50.043345Z","end":"2026-01-19T13:44:50.266252Z","steps":["trace[1418267162] 'process raft request' (duration: 222.044334ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:50.266336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10582652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T13:44:50.266416Z","caller":"traceutil/trace.go:171","msg":"trace[1690795297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"1.105927883s","start":"2026-01-19T13:44:49.160478Z","end":"2026-01-19T13:44:50.266406Z","steps":["trace[1690795297] 'agreement among raft nodes before linearized reading' (duration: 1.105829041s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:50.266446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:49.16047Z","time spent":"1.105969364s","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-19T13:44:56.322677Z","caller":"traceutil/trace.go:171","msg":"trace[150607977] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"161.401683ms","start":"2026-01-19T13:44:56.161256Z","end":"2026-01-19T13:44:56.322657Z","steps":["trace[150607977] 'read index received' (duration: 161.247349ms)","trace[150607977] 'applied index is now lower than readState.Index' (duration: 153.824µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:44:56.322761Z","caller":"traceutil/trace.go:171","msg":"trace[963471975] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"212.862497ms","start":"2026-01-19T13:44:56.109891Z","end":"2026-01-19T13:44:56.322754Z","steps":["trace[963471975] 'process raft request' (duration: 212.660972ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:56.322921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.648718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T13:44:56.322945Z","caller":"traceutil/trace.go:171","msg":"trace[361003297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"161.7177ms","start":"2026-01-19T13:44:56.16122Z","end":"2026-01-19T13:44:56.322938Z","steps":["trace[361003297] 'agreement among raft nodes before linearized reading' (duration: 161.662419ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:56.473594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.541963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T13:44:56.473654Z","caller":"traceutil/trace.go:171","msg":"trace[924055953] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"147.249642ms","start":"2026-01-19T13:44:56.326391Z","end":"2026-01-19T13:44:56.47364Z","steps":["trace[924055953] 'process raft request' (duration: 43.525945ms)","trace[924055953] 'compare' (duration: 103.487282ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:44:56.473982Z","caller":"traceutil/trace.go:171","msg":"trace[182780285] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"105.465227ms","start":"2026-01-19T13:44:56.36851Z","end":"2026-01-19T13:44:56.473975Z","steps":["trace[182780285] 'read index received' (duration: 1.411843ms)","trace[182780285] 'applied index is now lower than readState.Index' (duration: 104.052804ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:44:56.474079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.567249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T13:44:56.474092Z","caller":"traceutil/trace.go:171","msg":"trace[1117394706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"105.600239ms","start":"2026-01-19T13:44:56.368487Z","end":"2026-01-19T13:44:56.474087Z","steps":["trace[1117394706] 'agreement among raft nodes before linearized reading' (duration: 105.538018ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:44:56.474155Z","caller":"traceutil/trace.go:171","msg":"trace[1333488414] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"145.920962ms","start":"2026-01-19T13:44:56.328229Z","end":"2026-01-19T13:44:56.47415Z","steps":["trace[1333488414] 'process raft request' (duration: 145.692777ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:44:57.080397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.144224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T13:44:57.080856Z","caller":"traceutil/trace.go:171","msg":"trace[1126712506] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"543.775812ms","start":"2026-01-19T13:44:56.537065Z","end":"2026-01-19T13:44:57.080841Z","steps":["trace[1126712506] 'process raft request' (duration: 299.128278ms)","trace[1126712506] 'compare' (duration: 243.835556ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:44:57.080941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:44:56.537054Z","time spent":"543.862045ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:44:57.838767Z","caller":"traceutil/trace.go:171","msg":"trace[2022550646] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"158.423434ms","start":"2026-01-19T13:44:57.680322Z","end":"2026-01-19T13:44:57.838745Z","steps":["trace[2022550646] 'process raft request' (duration: 158.310202ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:45:10.790248Z","caller":"traceutil/trace.go:171","msg":"trace[354813633] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"113.580549ms","start":"2026-01-19T13:45:10.676654Z","end":"2026-01-19T13:45:10.790234Z","steps":["trace[354813633] 'process raft request' (duration: 113.491367ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:45:14.70624Z","caller":"traceutil/trace.go:171","msg":"trace[604772881] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"324.824239ms","start":"2026-01-19T13:45:14.38139Z","end":"2026-01-19T13:45:14.706214Z","steps":["trace[604772881] 'process raft request' (duration: 324.744447ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:14.706332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:14.381379Z","time spent":"324.90385ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T13:45:14.706464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.692345ms","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-19T13:45:14.706513Z","caller":"traceutil/trace.go:171","msg":"trace[1002577048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"179.772397ms","start":"2026-01-19T13:45:14.52673Z","end":"2026-01-19T13:45:14.706502Z","steps":["trace[1002577048] 'agreement among raft nodes before linearized reading' (duration: 179.545692ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:45:14.706237Z","caller":"traceutil/trace.go:171","msg":"trace[1613897721] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:780; }","duration":"179.44869ms","start":"2026-01-19T13:45:14.526766Z","end":"2026-01-19T13:45:14.706214Z","steps":["trace[1613897721] 'read index received' (duration: 179.44123ms)","trace[1613897721] 'applied index is now lower than readState.Index' (duration: 6.29µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:14.706606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.03419ms","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-19T13:45:14.706623Z","caller":"traceutil/trace.go:171","msg":"trace[1664994846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"175.06001ms","start":"2026-01-19T13:45:14.531557Z","end":"2026-01-19T13:45:14.706617Z","steps":["trace[1664994846] 'agreement among raft nodes before linearized reading' (duration: 175.001749ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:45:15.018894Z","caller":"traceutil/trace.go:171","msg":"trace[1967831359] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"216.938249ms","start":"2026-01-19T13:45:14.801939Z","end":"2026-01-19T13:45:15.018877Z","steps":["trace[1967831359] 'read index received' (duration: 193.12886ms)","trace[1967831359] 'applied index is now lower than readState.Index' (duration: 23.808809ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:15.019116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.108052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T13:45:15.019148Z","caller":"traceutil/trace.go:171","msg":"trace[897956666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"217.245556ms","start":"2026-01-19T13:45:14.801895Z","end":"2026-01-19T13:45:15.01914Z","steps":["trace[897956666] 'agreement among raft nodes before linearized reading' (duration: 217.053181ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:45:15.01951Z","caller":"traceutil/trace.go:171","msg":"trace[1086054562] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"307.728763ms","start":"2026-01-19T13:45:14.711772Z","end":"2026-01-19T13:45:15.019501Z","steps":["trace[1086054562] 'process raft request' (duration: 283.260469ms)","trace[1086054562] 'compare' (duration: 23.727987ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:15.019583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:14.711758Z","time spent":"307.781574ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:15.019646Z","caller":"traceutil/trace.go:171","msg":"trace[285020437] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"307.768304ms","start":"2026-01-19T13:45:14.711872Z","end":"2026-01-19T13:45:15.01964Z","steps":["trace[285020437] 'process raft request' (duration: 306.974966ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:15.019684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:14.711868Z","time spent":"307.797854ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:15.188371Z","caller":"traceutil/trace.go:171","msg":"trace[1017206746] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"164.635605ms","start":"2026-01-19T13:45:15.023717Z","end":"2026-01-19T13:45:15.188353Z","steps":["trace[1017206746] 'process raft request' (duration: 145.824539ms)","trace[1017206746] 'compare' (duration: 18.53528ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:45:15.325352Z","caller":"traceutil/trace.go:171","msg":"trace[2050273655] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"134.029403ms","start":"2026-01-19T13:45:15.191305Z","end":"2026-01-19T13:45:15.325334Z","steps":["trace[2050273655] 'process raft request' (duration: 102.812426ms)","trace[2050273655] 'compare' (duration: 31.140945ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:17.317882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.595935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T13:45:17.317986Z","caller":"traceutil/trace.go:171","msg":"trace[1426679649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"126.731778ms","start":"2026-01-19T13:45:17.191234Z","end":"2026-01-19T13:45:17.317966Z","steps":["trace[1426679649] 'range keys from in-memory index tree' (duration: 126.458392ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:45:17.582324Z","caller":"traceutil/trace.go:171","msg":"trace[1416349581] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"254.071569ms","start":"2026-01-19T13:45:17.328228Z","end":"2026-01-19T13:45:17.582299Z","steps":["trace[1416349581] 'read index received' (duration: 253.799863ms)","trace[1416349581] 'applied index is now lower than readState.Index' (duration: 270.626µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:45:17.582522Z","caller":"traceutil/trace.go:171","msg":"trace[1408789656] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"259.867979ms","start":"2026-01-19T13:45:17.322632Z","end":"2026-01-19T13:45:17.5825Z","steps":["trace[1408789656] 'process raft request' (duration: 259.479851ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:17.582873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.5648ms","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-19T13:45:17.582929Z","caller":"traceutil/trace.go:171","msg":"trace[2125648004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"254.713843ms","start":"2026-01-19T13:45:17.3282Z","end":"2026-01-19T13:45:17.582914Z","steps":["trace[2125648004] 'agreement among raft nodes before linearized reading' (duration: 254.475467ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:45:17.973916Z","caller":"traceutil/trace.go:171","msg":"trace[880585212] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"385.41844ms","start":"2026-01-19T13:45:17.588485Z","end":"2026-01-19T13:45:17.973903Z","steps":["trace[880585212] 'read index received' (duration: 367.595657ms)","trace[880585212] 'applied index is now lower than readState.Index' (duration: 17.821833ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:17.973978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:17.586477Z","time spent":"387.498498ms","remote":"127.0.0.1:39788","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-19T13:45:17.974114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.613135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T13:45:17.97413Z","caller":"traceutil/trace.go:171","msg":"trace[1129257076] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:762; }","duration":"385.654956ms","start":"2026-01-19T13:45:17.58847Z","end":"2026-01-19T13:45:17.974125Z","steps":["trace[1129257076] 'agreement among raft nodes before linearized reading' (duration: 385.599465ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:17.974144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:17.588465Z","time spent":"385.676206ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-01-19T13:45:17.998201Z","caller":"traceutil/trace.go:171","msg":"trace[1363099770] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"409.068326ms","start":"2026-01-19T13:45:17.589118Z","end":"2026-01-19T13:45:17.998186Z","steps":["trace[1363099770] 'process raft request' (duration: 408.957633ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:17.998473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:17.58911Z","time spent":"409.139907ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T13:45:18.000574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.457822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T13:45:18.000621Z","caller":"traceutil/trace.go:171","msg":"trace[333156675] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:763; }","duration":"393.535464ms","start":"2026-01-19T13:45:17.607075Z","end":"2026-01-19T13:45:18.00061Z","steps":["trace[333156675] 'agreement among raft nodes before linearized reading' (duration: 393.459362ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:18.000766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:17.607064Z","time spent":"393.688507ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-01-19T13:45:31.448007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.783657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T13:45:31.448146Z","caller":"traceutil/trace.go:171","msg":"trace[1180087102] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"361.220112ms","start":"2026-01-19T13:45:31.086913Z","end":"2026-01-19T13:45:31.448133Z","steps":["trace[1180087102] 'process raft request' (duration: 124.250601ms)","trace[1180087102] 'compare' (duration: 236.528971ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:31.448189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:31.086907Z","time spent":"361.261894ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:31.448297Z","caller":"traceutil/trace.go:171","msg":"trace[1004367224] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:886; }","duration":"288.008036ms","start":"2026-01-19T13:45:31.160266Z","end":"2026-01-19T13:45:31.448274Z","steps":["trace[1004367224] 'read index received' (duration: 50.860301ms)","trace[1004367224] 'applied index is now lower than readState.Index' (duration: 237.146915ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:31.448358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.088978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T13:45:31.448385Z","caller":"traceutil/trace.go:171","msg":"trace[196431201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:857; }","duration":"288.14048ms","start":"2026-01-19T13:45:31.160235Z","end":"2026-01-19T13:45:31.448375Z","steps":["trace[196431201] 'agreement among raft nodes before linearized reading' (duration: 288.077168ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:45:31.448577Z","caller":"traceutil/trace.go:171","msg":"trace[1405807774] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"328.790662ms","start":"2026-01-19T13:45:31.119778Z","end":"2026-01-19T13:45:31.448568Z","steps":["trace[1405807774] 'process raft request' (duration: 328.30144ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:31.448626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:31.119763Z","time spent":"328.835843ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T13:45:34.132278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.702968ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-19T13:45:34.132357Z","caller":"traceutil/trace.go:171","msg":"trace[632851158] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"680.414625ms","start":"2026-01-19T13:45:33.451929Z","end":"2026-01-19T13:45:34.132343Z","steps":["trace[632851158] 'read index received' (duration: 195.600324ms)","trace[632851158] 'applied index is now lower than readState.Index' (duration: 484.813181ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:34.13368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.739724ms","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-19T13:45:34.133733Z","caller":"traceutil/trace.go:171","msg":"trace[149683182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:861; }","duration":"681.819336ms","start":"2026-01-19T13:45:33.451902Z","end":"2026-01-19T13:45:34.133722Z","steps":["trace[149683182] 'agreement among raft nodes before linearized reading' (duration: 681.068339ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:34.133761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:33.451876Z","time spent":"681.879267ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-19T13:45:34.133955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.910068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T13:45:34.133982Z","caller":"traceutil/trace.go:171","msg":"trace[84824012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:861; }","duration":"460.961209ms","start":"2026-01-19T13:45:33.673014Z","end":"2026-01-19T13:45:34.133975Z","steps":["trace[84824012] 'agreement among raft nodes before linearized reading' (duration: 460.888908ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:34.134004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:33.673Z","time spent":"460.99937ms","remote":"127.0.0.1:58798","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-19T13:45:34.662044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085082811588461,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T13:45:34.821532Z","caller":"traceutil/trace.go:171","msg":"trace[1708845894] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"680.152249ms","start":"2026-01-19T13:45:34.141328Z","end":"2026-01-19T13:45:34.82148Z","steps":["trace[1708845894] 'process raft request' (duration: 679.939073ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:34.821769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:34.141312Z","time spent":"680.312432ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:34.822601Z","caller":"traceutil/trace.go:171","msg":"trace[1491734806] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:894; }","duration":"661.206429ms","start":"2026-01-19T13:45:34.161383Z","end":"2026-01-19T13:45:34.82259Z","steps":["trace[1491734806] 'read index received' (duration: 660.824791ms)","trace[1491734806] 'applied index is now lower than readState.Index' (duration: 381.028µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:45:34.822754Z","caller":"traceutil/trace.go:171","msg":"trace[1627997892] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"681.172421ms","start":"2026-01-19T13:45:34.141574Z","end":"2026-01-19T13:45:34.822746Z","steps":["trace[1627997892] 'process raft request' (duration: 680.929636ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:34.822805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:34.141568Z","time spent":"681.206833ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:34.822907Z","caller":"traceutil/trace.go:171","msg":"trace[1833877492] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"679.922723ms","start":"2026-01-19T13:45:34.142976Z","end":"2026-01-19T13:45:34.822898Z","steps":["trace[1833877492] 'process raft request' (duration: 679.588946ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:34.822945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:34.142967Z","time spent":"679.954614ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T13:45:34.823043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.65966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T13:45:34.823069Z","caller":"traceutil/trace.go:171","msg":"trace[1710668534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"661.685401ms","start":"2026-01-19T13:45:34.161375Z","end":"2026-01-19T13:45:34.82306Z","steps":["trace[1710668534] 'agreement among raft nodes before linearized reading' (duration: 661.64204ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:34.823093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:34.161361Z","time spent":"661.728292ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-19T13:45:38.724139Z","caller":"traceutil/trace.go:171","msg":"trace[791245108] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"319.11812ms","start":"2026-01-19T13:45:38.405004Z","end":"2026-01-19T13:45:38.724122Z","steps":["trace[791245108] 'process raft request' (duration: 318.988257ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:38.724231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:38.40499Z","time spent":"319.191092ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:39.197503Z","caller":"traceutil/trace.go:171","msg":"trace[496257025] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"312.654244ms","start":"2026-01-19T13:45:38.88483Z","end":"2026-01-19T13:45:39.197484Z","steps":["trace[496257025] 'process raft request' (duration: 312.352527ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:39.197627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:38.88482Z","time spent":"312.745356ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:39.19788Z","caller":"traceutil/trace.go:171","msg":"trace[288060015] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"311.634361ms","start":"2026-01-19T13:45:38.886236Z","end":"2026-01-19T13:45:39.19787Z","steps":["trace[288060015] 'process raft request' (duration: 311.60572ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:39.197947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:38.886233Z","time spent":"311.682322ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:39.198077Z","caller":"traceutil/trace.go:171","msg":"trace[958736917] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"313.140055ms","start":"2026-01-19T13:45:38.884929Z","end":"2026-01-19T13:45:39.198069Z","steps":["trace[958736917] 'process raft request' (duration: 312.814008ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:39.198129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:38.884924Z","time spent":"313.180496ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:39.198261Z","caller":"traceutil/trace.go:171","msg":"trace[468926278] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"312.07132ms","start":"2026-01-19T13:45:38.88618Z","end":"2026-01-19T13:45:39.198251Z","steps":["trace[468926278] 'process raft request' (duration: 311.62861ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:39.198318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:38.886174Z","time spent":"312.118761ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:45.546547Z","caller":"traceutil/trace.go:171","msg":"trace[1648052686] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"309.585114ms","start":"2026-01-19T13:45:45.236945Z","end":"2026-01-19T13:45:45.546531Z","steps":["trace[1648052686] 'read index received' (duration: 309.43768ms)","trace[1648052686] 'applied index is now lower than readState.Index' (duration: 146.774µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:45:45.546756Z","caller":"traceutil/trace.go:171","msg":"trace[835237180] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"310.402352ms","start":"2026-01-19T13:45:45.236346Z","end":"2026-01-19T13:45:45.546748Z","steps":["trace[835237180] 'process raft request' (duration: 310.099016ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:45.546833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:45.23633Z","time spent":"310.444334ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T13:45:45.546998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.048894ms","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-19T13:45:45.547019Z","caller":"traceutil/trace.go:171","msg":"trace[36865748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"310.071064ms","start":"2026-01-19T13:45:45.236942Z","end":"2026-01-19T13:45:45.547013Z","steps":["trace[36865748] 'agreement among raft nodes before linearized reading' (duration: 309.992913ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:45.547037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:45.23691Z","time spent":"310.122746ms","remote":"127.0.0.1:58798","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-19T13:45:45.547203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.725695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-19T13:45:45.547222Z","caller":"traceutil/trace.go:171","msg":"trace[616345268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"297.785277ms","start":"2026-01-19T13:45:45.249431Z","end":"2026-01-19T13:45:45.547216Z","steps":["trace[616345268] 'agreement among raft nodes before linearized reading' (duration: 297.732566ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:45.547532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.963783ms","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-19T13:45:45.547557Z","caller":"traceutil/trace.go:171","msg":"trace[60806200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"295.001504ms","start":"2026-01-19T13:45:45.252547Z","end":"2026-01-19T13:45:45.547548Z","steps":["trace[60806200] 'agreement among raft nodes before linearized reading' (duration: 294.938483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:45.54858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.75922ms","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-19T13:45:45.548604Z","caller":"traceutil/trace.go:171","msg":"trace[828312472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:890; }","duration":"291.795392ms","start":"2026-01-19T13:45:45.256803Z","end":"2026-01-19T13:45:45.548598Z","steps":["trace[828312472] 'agreement among raft nodes before linearized reading' (duration: 290.517222ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:46.146947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.192433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T13:45:46.147166Z","caller":"traceutil/trace.go:171","msg":"trace[1205801965] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"592.916024ms","start":"2026-01-19T13:45:45.554235Z","end":"2026-01-19T13:45:46.147151Z","steps":["trace[1205801965] 'process raft request' (duration: 592.882413ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:46.147227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:45.55423Z","time spent":"592.967695ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:46.147508Z","caller":"traceutil/trace.go:171","msg":"trace[1811527590] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"593.661812ms","start":"2026-01-19T13:45:45.553834Z","end":"2026-01-19T13:45:46.147496Z","steps":["trace[1811527590] 'process raft request' (duration: 398.852784ms)","trace[1811527590] 'compare' (duration: 193.834855ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T13:45:46.14756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:45.553821Z","time spent":"593.711253ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:46.147681Z","caller":"traceutil/trace.go:171","msg":"trace[1956492867] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"593.564669ms","start":"2026-01-19T13:45:45.554108Z","end":"2026-01-19T13:45:46.147672Z","steps":["trace[1956492867] 'process raft request' (duration: 592.926163ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:46.147725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:45.554096Z","time spent":"593.60539ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T13:45:46.147838Z","caller":"traceutil/trace.go:171","msg":"trace[1794687975] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"593.636521ms","start":"2026-01-19T13:45:45.554193Z","end":"2026-01-19T13:45:46.14783Z","steps":["trace[1794687975] 'process raft request' (duration: 592.885753ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:45:46.147885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T13:45:45.554181Z","time spent":"593.679702ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T13:45:50.441423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.473053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-19T13:45:50.441495Z","caller":"traceutil/trace.go:171","msg":"trace[480812731] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:906; }","duration":"119.585735ms","start":"2026-01-19T13:45:50.321893Z","end":"2026-01-19T13:45:50.441478Z","steps":["trace[480812731] 'count revisions from in-memory index tree' (duration: 118.537612ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:46:03.90258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.819618ms","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-19T13:46:03.90264Z","caller":"traceutil/trace.go:171","msg":"trace[1702368542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:976; }","duration":"143.916611ms","start":"2026-01-19T13:46:03.75871Z","end":"2026-01-19T13:46:03.902626Z","steps":["trace[1702368542] 'range keys from in-memory index tree' (duration: 143.716497ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:46:06.515554Z","caller":"traceutil/trace.go:171","msg":"trace[1131987214] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"117.842501ms","start":"2026-01-19T13:46:06.397693Z","end":"2026-01-19T13:46:06.515535Z","steps":["trace[1131987214] 'process raft request' (duration: 117.737959ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:46:06.53026Z","caller":"traceutil/trace.go:171","msg":"trace[1539559234] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"117.272469ms","start":"2026-01-19T13:46:06.412972Z","end":"2026-01-19T13:46:06.530244Z","steps":["trace[1539559234] 'read index received' (duration: 102.420383ms)","trace[1539559234] 'applied index is now lower than readState.Index' (duration: 14.851316ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T13:46:06.530333Z","caller":"traceutil/trace.go:171","msg":"trace[2039949030] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"131.667354ms","start":"2026-01-19T13:46:06.398661Z","end":"2026-01-19T13:46:06.530328Z","steps":["trace[2039949030] 'process raft request' (duration: 130.954027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:46:06.530502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.517764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-19T13:46:06.530519Z","caller":"traceutil/trace.go:171","msg":"trace[690739844] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"117.574546ms","start":"2026-01-19T13:46:06.412939Z","end":"2026-01-19T13:46:06.530514Z","steps":["trace[690739844] 'agreement among raft nodes before linearized reading' (duration: 117.493944ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:46:06.530668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.630417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-19T13:46:06.530728Z","caller":"traceutil/trace.go:171","msg":"trace[515501568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"117.712548ms","start":"2026-01-19T13:46:06.413005Z","end":"2026-01-19T13:46:06.530718Z","steps":["trace[515501568] 'agreement among raft nodes before linearized reading' (duration: 117.566825ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:46:08.081145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.92738ms","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-19T13:46:08.081193Z","caller":"traceutil/trace.go:171","msg":"trace[1008074305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"113.024392ms","start":"2026-01-19T13:46:07.968158Z","end":"2026-01-19T13:46:08.081182Z","steps":["trace[1008074305] 'range keys from in-memory index tree' (duration: 112.804218ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T13:49:07.09905Z","caller":"traceutil/trace.go:171","msg":"trace[1101696252] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"150.232057ms","start":"2026-01-19T13:49:06.948792Z","end":"2026-01-19T13:49:07.099024Z","steps":["trace[1101696252] 'process raft request' (duration: 149.993142ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:49:14.102845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.791466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-01-19T13:49:14.103194Z","caller":"traceutil/trace.go:171","msg":"trace[1719148560] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1803; }","duration":"111.178315ms","start":"2026-01-19T13:49:13.991998Z","end":"2026-01-19T13:49:14.103176Z","steps":["trace[1719148560] 'range keys from in-memory index tree' (duration: 110.648373ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T13:49:14.103648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.230859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-01-19T13:49:14.103828Z","caller":"traceutil/trace.go:171","msg":"trace[125367532] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1803; }","duration":"144.430414ms","start":"2026-01-19T13:49:13.959383Z","end":"2026-01-19T13:49:14.103814Z","steps":["trace[125367532] 'range keys from in-memory index tree' (duration: 144.143858ms)"],"step_count":1}