{"level":"warn","ts":"2026-02-04T05:08:54.227961Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T05:08:54.228077Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206: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.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-04T05:08:54.228153Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-04T05:08:54.228181Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-02-04T05:08:54.228203Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-04T05:08:54.232139Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-02-04T05:08:54.23224Z","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.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-04T05:08:54.239204Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.705974ms"} {"level":"info","ts":"2026-02-04T05:08:54.257117Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-02-04T05:08:54.257717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T05:08:54.257795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-02-04T05:08:54.25787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T05:08:54.25795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-02-04T05:08:54.258056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-02-04T05:08:54.271709Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T05:08:54.285227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T05:08:54.288155Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-04T05:08:54.296241Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T05:08:54.302287Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T05:08:54.302565Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-04T05:08:54.302673Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-04T05:08:54.30271Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-04T05:08:54.304057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-02-04T05:08:54.30415Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-02-04T05:08:54.321685Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-04T05:08:54.32184Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-02-04T05:08:54.321854Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-02-04T05:08:54.32199Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T05:08:54.322011Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T05:08:55.070511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T05:08:55.070547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T05:08:55.070561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-02-04T05:08:55.070571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-02-04T05:08:55.070582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-02-04T05:08:55.07059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-02-04T05:08:55.070597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-02-04T05:08:55.078334Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T05:08:55.084343Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:08:55.084429Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T05:08:55.084679Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T05:08:55.096722Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-02-04T05:08:55.097767Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T05:08:55.104096Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:08:55.104228Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:08:55.104322Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T05:08:55.104344Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T05:08:55.10438Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T05:09:05.089582Z","caller":"traceutil/trace.go:171","msg":"trace[306814615] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"118.970492ms","start":"2026-02-04T05:09:04.970593Z","end":"2026-02-04T05:09:05.089564Z","steps":["trace[306814615] 'read index received' (duration: 118.81379ms)","trace[306814615] 'applied index is now lower than readState.Index' (duration: 155.852µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:05.09094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.330253ms","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-02-04T05:09:05.099257Z","caller":"traceutil/trace.go:171","msg":"trace[1744740302] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:347; }","duration":"128.635334ms","start":"2026-02-04T05:09:04.970563Z","end":"2026-02-04T05:09:05.099198Z","steps":["trace[1744740302] 'agreement among raft nodes before linearized reading' (duration: 119.456575ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:05.091326Z","caller":"traceutil/trace.go:171","msg":"trace[210483439] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"134.278435ms","start":"2026-02-04T05:09:04.95703Z","end":"2026-02-04T05:09:05.091308Z","steps":["trace[210483439] 'process raft request' (duration: 132.41981ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:05.406227Z","caller":"traceutil/trace.go:171","msg":"trace[1883196668] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:357; }","duration":"136.792783ms","start":"2026-02-04T05:09:05.269415Z","end":"2026-02-04T05:09:05.406207Z","steps":["trace[1883196668] 'read index received' (duration: 5.117005ms)","trace[1883196668] 'applied index is now lower than readState.Index' (duration: 131.674338ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:05.406621Z","caller":"traceutil/trace.go:171","msg":"trace[2057859681] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"172.985775ms","start":"2026-02-04T05:09:05.233613Z","end":"2026-02-04T05:09:05.406598Z","steps":["trace[2057859681] 'process raft request' (duration: 167.306486ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:05.406791Z","caller":"traceutil/trace.go:171","msg":"trace[1997384840] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"170.661706ms","start":"2026-02-04T05:09:05.236112Z","end":"2026-02-04T05:09:05.406774Z","steps":["trace[1997384840] 'process raft request' (duration: 170.044821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:05.406943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.514259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-04T05:09:05.406967Z","caller":"traceutil/trace.go:171","msg":"trace[632230446] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:353; }","duration":"137.562349ms","start":"2026-02-04T05:09:05.269398Z","end":"2026-02-04T05:09:05.40696Z","steps":["trace[632230446] 'agreement among raft nodes before linearized reading' (duration: 137.480649ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:05.406988Z","caller":"traceutil/trace.go:171","msg":"trace[1468734241] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"172.886925ms","start":"2026-02-04T05:09:05.234091Z","end":"2026-02-04T05:09:05.406978Z","steps":["trace[1468734241] 'process raft request' (duration: 171.987728ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:05.4072Z","caller":"traceutil/trace.go:171","msg":"trace[572888241] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"172.063377ms","start":"2026-02-04T05:09:05.235127Z","end":"2026-02-04T05:09:05.407191Z","steps":["trace[572888241] 'process raft request' (duration: 170.993018ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:10.334644Z","caller":"traceutil/trace.go:171","msg":"trace[447969261] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"238.760244ms","start":"2026-02-04T05:09:10.095851Z","end":"2026-02-04T05:09:10.334611Z","steps":["trace[447969261] 'process raft request' (duration: 238.440231ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:15.803094Z","caller":"traceutil/trace.go:171","msg":"trace[1705048691] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"329.671475ms","start":"2026-02-04T05:09:15.473404Z","end":"2026-02-04T05:09:15.803076Z","steps":["trace[1705048691] 'read index received' (duration: 329.520044ms)","trace[1705048691] 'applied index is now lower than readState.Index' (duration: 150.561µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:15.803486Z","caller":"traceutil/trace.go:171","msg":"trace[326864337] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"333.667497ms","start":"2026-02-04T05:09:15.469804Z","end":"2026-02-04T05:09:15.803471Z","steps":["trace[326864337] 'process raft request' (duration: 333.165064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:15.804672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:15.469793Z","time spent":"333.728868ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:09:15.805014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.600901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:09:15.805191Z","caller":"traceutil/trace.go:171","msg":"trace[1208932270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:452; }","duration":"331.797623ms","start":"2026-02-04T05:09:15.47338Z","end":"2026-02-04T05:09:15.805178Z","steps":["trace[1208932270] 'agreement among raft nodes before linearized reading' (duration: 331.593682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:15.805244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:15.473374Z","time spent":"331.859774ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:09:15.805455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.874205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:09:15.805491Z","caller":"traceutil/trace.go:171","msg":"trace[1261663508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:452; }","duration":"330.970156ms","start":"2026-02-04T05:09:15.47451Z","end":"2026-02-04T05:09:15.80548Z","steps":["trace[1261663508] 'agreement among raft nodes before linearized reading' (duration: 330.862625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:15.805685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:15.474505Z","time spent":"331.167257ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T05:09:20.048325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.031518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:09:20.048407Z","caller":"traceutil/trace.go:171","msg":"trace[279501797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"129.152629ms","start":"2026-02-04T05:09:19.919236Z","end":"2026-02-04T05:09:20.048388Z","steps":["trace[279501797] 'range keys from in-memory index tree' (duration: 128.921608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:20.048352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.031379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:09:20.048487Z","caller":"traceutil/trace.go:171","msg":"trace[1685079154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"129.18875ms","start":"2026-02-04T05:09:19.91929Z","end":"2026-02-04T05:09:20.048479Z","steps":["trace[1685079154] 'range keys from in-memory index tree' (duration: 128.934098ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:21.195969Z","caller":"traceutil/trace.go:171","msg":"trace[889612575] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"178.055636ms","start":"2026-02-04T05:09:21.017886Z","end":"2026-02-04T05:09:21.195941Z","steps":["trace[889612575] 'process raft request' (duration: 177.885465ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:23.435054Z","caller":"traceutil/trace.go:171","msg":"trace[14337807] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"113.422737ms","start":"2026-02-04T05:09:23.321612Z","end":"2026-02-04T05:09:23.435035Z","steps":["trace[14337807] 'process raft request' (duration: 111.259641ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:26.019071Z","caller":"traceutil/trace.go:171","msg":"trace[1401227712] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"166.043966ms","start":"2026-02-04T05:09:25.852811Z","end":"2026-02-04T05:09:26.018855Z","steps":["trace[1401227712] 'process raft request' (duration: 165.927735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:26.633425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226105947813448,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:09:27.133858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226105947813448,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:09:27.634266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226105947813448,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:09:27.799152Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.77179092s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T05:09:27.941684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.115296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:09:27.941787Z","caller":"traceutil/trace.go:171","msg":"trace[2120819308] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"1.808894405s","start":"2026-02-04T05:09:26.132875Z","end":"2026-02-04T05:09:27.941769Z","steps":["trace[2120819308] 'read index received' (duration: 1.666427006s)","trace[2120819308] 'applied index is now lower than readState.Index' (duration: 142.466109ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:27.943514Z","caller":"traceutil/trace.go:171","msg":"trace[336395821] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.916239543s","start":"2026-02-04T05:09:26.027252Z","end":"2026-02-04T05:09:27.943491Z","steps":["trace[336395821] 'process raft request' (duration: 1.772109872s)","trace[336395821] 'compare' (duration: 141.938365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:27.943573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:26.027228Z","time spent":"1.916315865s","remote":"127.0.0.1:57650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:09:27.943829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.810946051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T05:09:27.943869Z","caller":"traceutil/trace.go:171","msg":"trace[138859203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"1.811010251s","start":"2026-02-04T05:09:26.132846Z","end":"2026-02-04T05:09:27.943857Z","steps":["trace[138859203] 'agreement among raft nodes before linearized reading' (duration: 1.8108879s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:27.943897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:26.132832Z","time spent":"1.811057131s","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-04T05:09:28.411574Z","caller":"traceutil/trace.go:171","msg":"trace[1776803968] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"469.738638ms","start":"2026-02-04T05:09:27.941811Z","end":"2026-02-04T05:09:28.41155Z","steps":["trace[1776803968] 'read index received' (duration: 469.131394ms)","trace[1776803968] 'applied index is now lower than readState.Index' (duration: 606.194µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:28.411666Z","caller":"traceutil/trace.go:171","msg":"trace[2144213040] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"544.68774ms","start":"2026-02-04T05:09:27.866957Z","end":"2026-02-04T05:09:28.411645Z","steps":["trace[2144213040] 'process raft request' (duration: 543.973155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:28.411758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.913989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-04T05:09:28.411782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:27.866922Z","time spent":"544.801431ms","remote":"127.0.0.1:57754","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-02-04T05:09:28.411794Z","caller":"traceutil/trace.go:171","msg":"trace[2036284256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"634.012469ms","start":"2026-02-04T05:09:27.77777Z","end":"2026-02-04T05:09:28.411782Z","steps":["trace[2036284256] 'agreement among raft nodes before linearized reading' (duration: 633.914768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:28.411829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:27.77774Z","time spent":"634.080249ms","remote":"127.0.0.1:57484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:09:28.412841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93840085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-04T05:09:28.412861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.270714623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T05:09:28.412879Z","caller":"traceutil/trace.go:171","msg":"trace[1631321134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"1.93846907s","start":"2026-02-04T05:09:26.474399Z","end":"2026-02-04T05:09:28.412868Z","steps":["trace[1631321134] 'agreement among raft nodes before linearized reading' (duration: 1.938338559s)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:28.412891Z","caller":"traceutil/trace.go:171","msg":"trace[1964290290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"2.270759003s","start":"2026-02-04T05:09:26.142124Z","end":"2026-02-04T05:09:28.412883Z","steps":["trace[1964290290] 'agreement among raft nodes before linearized reading' (duration: 2.270702793s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:28.412914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:26.142091Z","time spent":"2.270818973s","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:09:28.412913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:26.474369Z","time spent":"1.93853718s","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T05:09:28.416685Z","caller":"traceutil/trace.go:171","msg":"trace[1153654498] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"456.892632ms","start":"2026-02-04T05:09:27.959781Z","end":"2026-02-04T05:09:28.416674Z","steps":["trace[1153654498] 'process raft request' (duration: 456.740771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:28.416874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:27.959758Z","time spent":"457.046043ms","remote":"127.0.0.1:57754","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-02-04T05:09:28.417115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.152224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T05:09:28.418076Z","caller":"traceutil/trace.go:171","msg":"trace[885012541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"394.145721ms","start":"2026-02-04T05:09:28.023921Z","end":"2026-02-04T05:09:28.418066Z","steps":["trace[885012541] 'agreement among raft nodes before linearized reading' (duration: 393.134204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:28.418213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:28.023899Z","time spent":"394.279932ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T05:09:31.247327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.721481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:09:31.24743Z","caller":"traceutil/trace.go:171","msg":"trace[600091731] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"201.189271ms","start":"2026-02-04T05:09:31.046223Z","end":"2026-02-04T05:09:31.247412Z","steps":["trace[600091731] 'process raft request' (duration: 62.226329ms)","trace[600091731] 'compare' (duration: 138.61686ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:31.750897Z","caller":"traceutil/trace.go:171","msg":"trace[309221008] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"704.378466ms","start":"2026-02-04T05:09:31.046492Z","end":"2026-02-04T05:09:31.75087Z","steps":["trace[309221008] 'process raft request' (duration: 661.050287ms)","trace[309221008] 'compare' (duration: 42.806675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:31.751014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:31.046487Z","time spent":"704.474016ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3048,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:09:31.751732Z","caller":"traceutil/trace.go:171","msg":"trace[1790972124] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:547; }","duration":"446.575708ms","start":"2026-02-04T05:09:31.305147Z","end":"2026-02-04T05:09:31.751722Z","steps":["trace[1790972124] 'read index received' (duration: 402.403843ms)","trace[1790972124] 'applied index is now lower than readState.Index' (duration: 44.171395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:31.751938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.738989ms","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:1 size:12727"} {"level":"info","ts":"2026-02-04T05:09:31.751981Z","caller":"traceutil/trace.go:171","msg":"trace[929831132] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:534; }","duration":"446.850779ms","start":"2026-02-04T05:09:31.305121Z","end":"2026-02-04T05:09:31.751972Z","steps":["trace[929831132] 'agreement among raft nodes before linearized reading' (duration: 446.649328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:31.752005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:31.305101Z","time spent":"446.89664ms","remote":"127.0.0.1:57568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12751,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-04T05:09:31.752195Z","caller":"traceutil/trace.go:171","msg":"trace[610794786] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"493.751625ms","start":"2026-02-04T05:09:31.258436Z","end":"2026-02-04T05:09:31.752188Z","steps":["trace[610794786] 'process raft request' (duration: 492.364705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:31.752237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:31.258412Z","time spent":"493.799736ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:09:31.941556Z","caller":"traceutil/trace.go:171","msg":"trace[1634456535] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"136.610306ms","start":"2026-02-04T05:09:31.804913Z","end":"2026-02-04T05:09:31.941524Z","steps":["trace[1634456535] 'process raft request' (duration: 136.355095ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:31.941884Z","caller":"traceutil/trace.go:171","msg":"trace[1585031706] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:552; }","duration":"124.005413ms","start":"2026-02-04T05:09:31.817843Z","end":"2026-02-04T05:09:31.941849Z","steps":["trace[1585031706] 'read index received' (duration: 31.365471ms)","trace[1585031706] 'applied index is now lower than readState.Index' (duration: 92.638172ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:31.942111Z","caller":"traceutil/trace.go:171","msg":"trace[1134177432] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"132.054132ms","start":"2026-02-04T05:09:31.810044Z","end":"2026-02-04T05:09:31.942098Z","steps":["trace[1134177432] 'process raft request' (duration: 131.379438ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:31.94249Z","caller":"traceutil/trace.go:171","msg":"trace[1125370786] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"132.495915ms","start":"2026-02-04T05:09:31.809979Z","end":"2026-02-04T05:09:31.942475Z","steps":["trace[1125370786] 'process raft request' (duration: 131.410387ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:31.943387Z","caller":"traceutil/trace.go:171","msg":"trace[270516276] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"133.213751ms","start":"2026-02-04T05:09:31.810158Z","end":"2026-02-04T05:09:31.943372Z","steps":["trace[270516276] 'process raft request' (duration: 131.409048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:31.943936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.075448ms","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:1 size:12727"} {"level":"info","ts":"2026-02-04T05:09:31.944004Z","caller":"traceutil/trace.go:171","msg":"trace[73150327] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:541; }","duration":"126.196229ms","start":"2026-02-04T05:09:31.817789Z","end":"2026-02-04T05:09:31.943986Z","steps":["trace[73150327] 'agreement among raft nodes before linearized reading' (duration: 126.004337ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:32.148939Z","caller":"traceutil/trace.go:171","msg":"trace[778944379] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"193.318889ms","start":"2026-02-04T05:09:31.955602Z","end":"2026-02-04T05:09:32.148921Z","steps":["trace[778944379] 'process raft request' (duration: 105.562076ms)","trace[778944379] 'compare' (duration: 87.514341ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:32.198092Z","caller":"traceutil/trace.go:171","msg":"trace[1378131858] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"240.063732ms","start":"2026-02-04T05:09:31.958008Z","end":"2026-02-04T05:09:32.198072Z","steps":["trace[1378131858] 'process raft request' (duration: 239.946951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:32.939338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226105947813511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:09:33.52337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226105947813511,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:09:33.571877Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.366666863s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T05:09:33.575789Z","caller":"traceutil/trace.go:171","msg":"trace[1354346894] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"1.137081908s","start":"2026-02-04T05:09:32.43869Z","end":"2026-02-04T05:09:33.575772Z","steps":["trace[1354346894] 'read index received' (duration: 1.133284621s)","trace[1354346894] 'applied index is now lower than readState.Index' (duration: 3.796747ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:33.57596Z","caller":"traceutil/trace.go:171","msg":"trace[880696463] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.370848144s","start":"2026-02-04T05:09:32.205103Z","end":"2026-02-04T05:09:33.575952Z","steps":["trace[880696463] 'process raft request' (duration: 1.366923504s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:33.576045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:32.205078Z","time spent":"1.370911504s","remote":"127.0.0.1:57952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3677,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:09:33.576208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137520372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:09:33.576231Z","caller":"traceutil/trace.go:171","msg":"trace[1310683076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.137575823s","start":"2026-02-04T05:09:32.438648Z","end":"2026-02-04T05:09:33.576224Z","steps":["trace[1310683076] 'agreement among raft nodes before linearized reading' (duration: 1.137493062s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:33.576249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:32.438633Z","time spent":"1.137611493s","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T05:09:33.576357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125212332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:09:33.576376Z","caller":"traceutil/trace.go:171","msg":"trace[448382736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"1.125253104s","start":"2026-02-04T05:09:32.451117Z","end":"2026-02-04T05:09:33.57637Z","steps":["trace[448382736] 'agreement among raft nodes before linearized reading' (duration: 1.125202513s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:33.576392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:32.451077Z","time spent":"1.125311284s","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T05:09:33.576513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.145987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:33.576535Z","caller":"traceutil/trace.go:171","msg":"trace[1509766821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"801.195797ms","start":"2026-02-04T05:09:32.775333Z","end":"2026-02-04T05:09:33.576529Z","steps":["trace[1509766821] 'agreement among raft nodes before linearized reading' (duration: 801.163326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:33.576552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:32.775303Z","time spent":"801.244747ms","remote":"127.0.0.1:57484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:09:33.576704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122978286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T05:09:33.576726Z","caller":"traceutil/trace.go:171","msg":"trace[196299382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"1.123029137s","start":"2026-02-04T05:09:32.45369Z","end":"2026-02-04T05:09:33.576719Z","steps":["trace[196299382] 'agreement among raft nodes before linearized reading' (duration: 1.122925816s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:33.576745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:32.453672Z","time spent":"1.123067987s","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T05:09:33.576843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124438048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:09:33.576861Z","caller":"traceutil/trace.go:171","msg":"trace[829559248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.124516928s","start":"2026-02-04T05:09:32.452339Z","end":"2026-02-04T05:09:33.576856Z","steps":["trace[829559248] 'agreement among raft nodes before linearized reading' (duration: 1.124467858s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:33.576878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:32.452329Z","time spent":"1.124544768s","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T05:09:37.779162Z","caller":"traceutil/trace.go:171","msg":"trace[158070480] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"178.119383ms","start":"2026-02-04T05:09:37.60103Z","end":"2026-02-04T05:09:37.77915Z","steps":["trace[158070480] 'read index received' (duration: 177.993671ms)","trace[158070480] 'applied index is now lower than readState.Index' (duration: 125.192µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:37.77943Z","caller":"traceutil/trace.go:171","msg":"trace[595262147] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"226.83459ms","start":"2026-02-04T05:09:37.552589Z","end":"2026-02-04T05:09:37.779424Z","steps":["trace[595262147] 'process raft request' (duration: 226.479357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:37.779658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.620348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T05:09:37.779679Z","caller":"traceutil/trace.go:171","msg":"trace[1897726710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"178.693388ms","start":"2026-02-04T05:09:37.600981Z","end":"2026-02-04T05:09:37.779674Z","steps":["trace[1897726710] 'agreement among raft nodes before linearized reading' (duration: 178.599747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:37.77988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.730679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:09:37.779899Z","caller":"traceutil/trace.go:171","msg":"trace[1317335030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"167.76895ms","start":"2026-02-04T05:09:37.612126Z","end":"2026-02-04T05:09:37.779895Z","steps":["trace[1317335030] 'agreement among raft nodes before linearized reading' (duration: 167.72124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:37.78017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.750924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:09:37.780188Z","caller":"traceutil/trace.go:171","msg":"trace[1977313236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"161.788854ms","start":"2026-02-04T05:09:37.618395Z","end":"2026-02-04T05:09:37.780184Z","steps":["trace[1977313236] 'agreement among raft nodes before linearized reading' (duration: 161.744664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:37.780367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.326921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:09:37.780385Z","caller":"traceutil/trace.go:171","msg":"trace[1464036121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"162.362612ms","start":"2026-02-04T05:09:37.618018Z","end":"2026-02-04T05:09:37.780381Z","steps":["trace[1464036121] 'agreement among raft nodes before linearized reading' (duration: 162.316101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:38.421136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.260673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:09:38.42123Z","caller":"traceutil/trace.go:171","msg":"trace[344197656] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"633.225455ms","start":"2026-02-04T05:09:37.787989Z","end":"2026-02-04T05:09:38.421214Z","steps":["trace[344197656] 'process raft request' (duration: 55.820536ms)","trace[344197656] 'compare' (duration: 576.943721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:38.421278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:37.787971Z","time spent":"633.282557ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:09:38.517509Z","caller":"traceutil/trace.go:171","msg":"trace[1570635101] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"728.211143ms","start":"2026-02-04T05:09:37.789277Z","end":"2026-02-04T05:09:38.517488Z","steps":["trace[1570635101] 'process raft request' (duration: 728.176462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:38.517641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:37.789271Z","time spent":"728.301016ms","remote":"127.0.0.1:57754","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-02-04T05:09:38.517928Z","caller":"traceutil/trace.go:171","msg":"trace[319073006] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"729.912034ms","start":"2026-02-04T05:09:37.788005Z","end":"2026-02-04T05:09:38.517917Z","steps":["trace[319073006] 'process raft request' (duration: 729.287849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:38.517981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:37.787996Z","time spent":"729.954836ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:09:38.51809Z","caller":"traceutil/trace.go:171","msg":"trace[1342991996] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"729.236052ms","start":"2026-02-04T05:09:37.788846Z","end":"2026-02-04T05:09:38.518082Z","steps":["trace[1342991996] 'process raft request' (duration: 728.574396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:38.518135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:37.788837Z","time spent":"729.272823ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:09:38.637846Z","caller":"traceutil/trace.go:171","msg":"trace[673507041] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"199.775828ms","start":"2026-02-04T05:09:38.438055Z","end":"2026-02-04T05:09:38.63783Z","steps":["trace[673507041] 'process raft request' (duration: 139.115445ms)","trace[673507041] 'compare' (duration: 60.33473ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:39.275836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226105947813550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:09:39.77615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4056226105947813550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T05:09:40.267259Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.62769365s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-04T05:09:40.775095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000063038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-04T05:09:40.775173Z","caller":"traceutil/trace.go:171","msg":"trace[1344067598] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000188202s","start":"2026-02-04T05:09:38.77497Z","end":"2026-02-04T05:09:40.775158Z","steps":["trace[1344067598] 'agreement among raft nodes before linearized reading' (duration: 2.000086569s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:40.775206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:38.774952Z","time spent":"2.000247665s","remote":"127.0.0.1:57484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 05:09:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T05:09:41.218503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.069097ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-04T05:09:41.21856Z","caller":"traceutil/trace.go:171","msg":"trace[149604106] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"2.443540674s","start":"2026-02-04T05:09:38.775009Z","end":"2026-02-04T05:09:41.21855Z","steps":["trace[149604106] 'read index received' (duration: 1.492359374s)","trace[149604106] 'applied index is now lower than readState.Index' (duration: 951.18037ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:41.218609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:38.639447Z","time spent":"2.579160851s","remote":"127.0.0.1:57500","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-04T05:09:41.218767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.167818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:09:41.218781Z","caller":"traceutil/trace.go:171","msg":"trace[1901103415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"792.209179ms","start":"2026-02-04T05:09:40.426566Z","end":"2026-02-04T05:09:41.218776Z","steps":["trace[1901103415] 'agreement among raft nodes before linearized reading' (duration: 792.134836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:41.218795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:40.426552Z","time spent":"792.23928ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T05:09:41.21888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.631792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:41.218892Z","caller":"traceutil/trace.go:171","msg":"trace[417518742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"443.671333ms","start":"2026-02-04T05:09:40.775217Z","end":"2026-02-04T05:09:41.218888Z","steps":["trace[417518742] 'agreement among raft nodes before linearized reading' (duration: 443.651122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:41.218904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:40.775202Z","time spent":"443.699194ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:09:41.218977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.635529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:09:41.218989Z","caller":"traceutil/trace.go:171","msg":"trace[641458976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"691.65988ms","start":"2026-02-04T05:09:40.527325Z","end":"2026-02-04T05:09:41.218985Z","steps":["trace[641458976] 'agreement among raft nodes before linearized reading' (duration: 691.625868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:41.219001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:40.527314Z","time spent":"691.684751ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:09:41.21907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.57344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:09:41.219083Z","caller":"traceutil/trace.go:171","msg":"trace[802609176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"695.621982ms","start":"2026-02-04T05:09:40.523456Z","end":"2026-02-04T05:09:41.219078Z","steps":["trace[802609176] 'agreement among raft nodes before linearized reading' (duration: 695.587801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:41.219094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:40.523436Z","time spent":"695.655113ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T05:09:41.219162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.681054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T05:09:41.219173Z","caller":"traceutil/trace.go:171","msg":"trace[1745615295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"695.724316ms","start":"2026-02-04T05:09:40.523445Z","end":"2026-02-04T05:09:41.219169Z","steps":["trace[1745615295] 'agreement among raft nodes before linearized reading' (duration: 695.693034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:41.219184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:40.523426Z","time spent":"695.754757ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T05:09:43.716774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.406495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:09:43.71684Z","caller":"traceutil/trace.go:171","msg":"trace[1873114978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"447.502579ms","start":"2026-02-04T05:09:43.269321Z","end":"2026-02-04T05:09:43.716824Z","steps":["trace[1873114978] 'range keys from in-memory index tree' (duration: 447.297211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:43.716876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:43.269306Z","time spent":"447.561341ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T05:09:43.717068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.80459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:09:43.717122Z","caller":"traceutil/trace.go:171","msg":"trace[338455011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"447.885163ms","start":"2026-02-04T05:09:43.269228Z","end":"2026-02-04T05:09:43.717113Z","steps":["trace[338455011] 'range keys from in-memory index tree' (duration: 447.561342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:43.717154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:43.269213Z","time spent":"447.934295ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T05:09:43.717303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.840212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T05:09:43.717335Z","caller":"traceutil/trace.go:171","msg":"trace[1872152899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"447.879773ms","start":"2026-02-04T05:09:43.269447Z","end":"2026-02-04T05:09:43.717327Z","steps":["trace[1872152899] 'range keys from in-memory index tree' (duration: 447.77371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:43.717364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:43.269442Z","time spent":"447.915434ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T05:09:43.717508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.128222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:09:43.717539Z","caller":"traceutil/trace.go:171","msg":"trace[1181965249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"448.167543ms","start":"2026-02-04T05:09:43.269364Z","end":"2026-02-04T05:09:43.717531Z","steps":["trace[1181965249] 'range keys from in-memory index tree' (duration: 447.965496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:43.717567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:43.26936Z","time spent":"448.201015ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T05:09:43.717683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.466549ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:43.717766Z","caller":"traceutil/trace.go:171","msg":"trace[1714664383] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:576; }","duration":"283.529021ms","start":"2026-02-04T05:09:43.434206Z","end":"2026-02-04T05:09:43.717735Z","steps":["trace[1714664383] 'range keys from in-memory index tree' (duration: 283.460268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:43.718017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.357528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2vb99\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-02-04T05:09:43.718053Z","caller":"traceutil/trace.go:171","msg":"trace[2000918400] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2vb99; range_end:; response_count:1; response_revision:576; }","duration":"111.41812ms","start":"2026-02-04T05:09:43.606627Z","end":"2026-02-04T05:09:43.718045Z","steps":["trace[2000918400] 'range keys from in-memory index tree' (duration: 111.275475ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:45.089515Z","caller":"traceutil/trace.go:171","msg":"trace[1945703736] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"201.58474ms","start":"2026-02-04T05:09:44.88791Z","end":"2026-02-04T05:09:45.089495Z","steps":["trace[1945703736] 'process raft request' (duration: 189.909008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:47.013913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.56088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-04T05:09:47.013982Z","caller":"traceutil/trace.go:171","msg":"trace[6969872] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:635; }","duration":"128.681994ms","start":"2026-02-04T05:09:46.885282Z","end":"2026-02-04T05:09:47.013964Z","steps":["trace[6969872] 'range keys from in-memory index tree' (duration: 128.414805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:48.628818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.440369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-04T05:09:48.628875Z","caller":"traceutil/trace.go:171","msg":"trace[1827980908] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:649; }","duration":"138.534992ms","start":"2026-02-04T05:09:48.490326Z","end":"2026-02-04T05:09:48.628861Z","steps":["trace[1827980908] 'range keys from in-memory index tree' (duration: 138.267373ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:48.629831Z","caller":"traceutil/trace.go:171","msg":"trace[1876040519] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"110.116867ms","start":"2026-02-04T05:09:48.519707Z","end":"2026-02-04T05:09:48.629824Z","steps":["trace[1876040519] 'process raft request' (duration: 109.690632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.051584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.536417ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-04T05:09:49.051788Z","caller":"traceutil/trace.go:171","msg":"trace[2066251475] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"243.034213ms","start":"2026-02-04T05:09:48.808741Z","end":"2026-02-04T05:09:49.051775Z","steps":["trace[2066251475] 'process raft request' (duration: 242.95937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.051902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:48.646148Z","time spent":"405.751761ms","remote":"127.0.0.1:57500","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-04T05:09:49.051967Z","caller":"traceutil/trace.go:171","msg":"trace[412218514] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"369.540933ms","start":"2026-02-04T05:09:48.682421Z","end":"2026-02-04T05:09:49.051962Z","steps":["trace[412218514] 'read index received' (duration: 66.632584ms)","trace[412218514] 'applied index is now lower than readState.Index' (duration: 302.906099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:49.052143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.73218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2vb99\" ","response":"range_response_count:1 size:18075"} {"level":"info","ts":"2026-02-04T05:09:49.052159Z","caller":"traceutil/trace.go:171","msg":"trace[192490676] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2vb99; range_end:; response_count:1; response_revision:651; }","duration":"369.776062ms","start":"2026-02-04T05:09:48.682377Z","end":"2026-02-04T05:09:49.052153Z","steps":["trace[192490676] 'agreement among raft nodes before linearized reading' (duration: 369.70588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.052172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:48.682359Z","time spent":"369.809942ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18099,"request content":"key:\"/registry/pods/kube-system/cilium-2vb99\" "} {"level":"warn","ts":"2026-02-04T05:09:49.052246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.041119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.052269Z","caller":"traceutil/trace.go:171","msg":"trace[528615064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"272.086501ms","start":"2026-02-04T05:09:48.780179Z","end":"2026-02-04T05:09:49.052265Z","steps":["trace[528615064] 'agreement among raft nodes before linearized reading' (duration: 272.05658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.053331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.27375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-04T05:09:49.05343Z","caller":"traceutil/trace.go:171","msg":"trace[1101830434] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:651; }","duration":"257.422725ms","start":"2026-02-04T05:09:48.795986Z","end":"2026-02-04T05:09:49.053409Z","steps":["trace[1101830434] 'agreement among raft nodes before linearized reading' (duration: 256.966229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.359343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.571657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:09:49.359411Z","caller":"traceutil/trace.go:171","msg":"trace[500946330] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"256.606037ms","start":"2026-02-04T05:09:49.102793Z","end":"2026-02-04T05:09:49.359399Z","steps":["trace[500946330] 'read index received' (duration: 122.954577ms)","trace[500946330] 'applied index is now lower than readState.Index' (duration: 133.65072ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:49.359557Z","caller":"traceutil/trace.go:171","msg":"trace[580161642] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"292.223551ms","start":"2026-02-04T05:09:49.067326Z","end":"2026-02-04T05:09:49.359549Z","steps":["trace[580161642] 'process raft request' (duration: 158.412196ms)","trace[580161642] 'compare' (duration: 133.464903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:49.359723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.928267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-04T05:09:49.359748Z","caller":"traceutil/trace.go:171","msg":"trace[1510587689] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:653; }","duration":"256.972759ms","start":"2026-02-04T05:09:49.102769Z","end":"2026-02-04T05:09:49.359742Z","steps":["trace[1510587689] 'agreement among raft nodes before linearized reading' (duration: 256.925868ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:49.37293Z","caller":"traceutil/trace.go:171","msg":"trace[1149988594] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"182.314405ms","start":"2026-02-04T05:09:49.190605Z","end":"2026-02-04T05:09:49.372919Z","steps":["trace[1149988594] 'process raft request' (duration: 182.20269ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:49.373095Z","caller":"traceutil/trace.go:171","msg":"trace[1552823273] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:655; }","duration":"100.004904ms","start":"2026-02-04T05:09:49.273084Z","end":"2026-02-04T05:09:49.373089Z","steps":["trace[1552823273] 'agreement among raft nodes before linearized reading' (duration: 99.997204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.373177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.092127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.373208Z","caller":"traceutil/trace.go:171","msg":"trace[1192858678] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:655; }","duration":"100.118668ms","start":"2026-02-04T05:09:49.273069Z","end":"2026-02-04T05:09:49.373188Z","steps":["trace[1192858678] 'agreement among raft nodes before linearized reading' (duration: 100.089687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.373272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.219091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.373287Z","caller":"traceutil/trace.go:171","msg":"trace[1226909733] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:655; }","duration":"100.249802ms","start":"2026-02-04T05:09:49.273031Z","end":"2026-02-04T05:09:49.37328Z","steps":["trace[1226909733] 'agreement among raft nodes before linearized reading' (duration: 100.228902ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:49.373302Z","caller":"traceutil/trace.go:171","msg":"trace[44229320] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"178.505083ms","start":"2026-02-04T05:09:49.194779Z","end":"2026-02-04T05:09:49.373285Z","steps":["trace[44229320] 'process raft request' (duration: 178.078157ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:49.570446Z","caller":"traceutil/trace.go:171","msg":"trace[1731348192] transaction","detail":"{read_only:false; number_of_response:1; response_revision:656; }","duration":"178.923107ms","start":"2026-02-04T05:09:49.391514Z","end":"2026-02-04T05:09:49.570437Z","steps":["trace[1731348192] 'process raft request' (duration: 178.872175ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:49.570603Z","caller":"traceutil/trace.go:171","msg":"trace[275367322] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"186.643374ms","start":"2026-02-04T05:09:49.383954Z","end":"2026-02-04T05:09:49.570598Z","steps":["trace[275367322] 'process raft request' (duration: 94.42163ms)","trace[275367322] 'compare' (duration: 91.719237ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:49.571141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.047034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.571183Z","caller":"traceutil/trace.go:171","msg":"trace[749784132] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:656; }","duration":"145.125136ms","start":"2026-02-04T05:09:49.426031Z","end":"2026-02-04T05:09:49.571156Z","steps":["trace[749784132] 'agreement among raft nodes before linearized reading' (duration: 144.760764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.571355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.169356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.571372Z","caller":"traceutil/trace.go:171","msg":"trace[694497858] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:656; }","duration":"145.187887ms","start":"2026-02-04T05:09:49.426179Z","end":"2026-02-04T05:09:49.571367Z","steps":["trace[694497858] 'agreement among raft nodes before linearized reading' (duration: 145.157136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.571669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.532379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.571684Z","caller":"traceutil/trace.go:171","msg":"trace[2028056006] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:656; }","duration":"145.55947ms","start":"2026-02-04T05:09:49.426121Z","end":"2026-02-04T05:09:49.571681Z","steps":["trace[2028056006] 'agreement among raft nodes before linearized reading' (duration: 145.512349ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:49.5716Z","caller":"traceutil/trace.go:171","msg":"trace[1222506819] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"144.224634ms","start":"2026-02-04T05:09:49.426186Z","end":"2026-02-04T05:09:49.57041Z","steps":["trace[1222506819] 'read index received' (duration: 52.195677ms)","trace[1222506819] 'applied index is now lower than readState.Index' (duration: 92.028127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:49.571829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.852365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.571847Z","caller":"traceutil/trace.go:171","msg":"trace[1215566676] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:656; }","duration":"133.885017ms","start":"2026-02-04T05:09:49.437957Z","end":"2026-02-04T05:09:49.571842Z","steps":["trace[1215566676] 'agreement among raft nodes before linearized reading' (duration: 133.858176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.571973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.945109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.571987Z","caller":"traceutil/trace.go:171","msg":"trace[1790612811] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:656; }","duration":"133.96631ms","start":"2026-02-04T05:09:49.438017Z","end":"2026-02-04T05:09:49.571983Z","steps":["trace[1790612811] 'agreement among raft nodes before linearized reading' (duration: 133.942569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.572068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.051903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.572082Z","caller":"traceutil/trace.go:171","msg":"trace[735007077] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:656; }","duration":"134.074373ms","start":"2026-02-04T05:09:49.438003Z","end":"2026-02-04T05:09:49.572077Z","steps":["trace[735007077] 'agreement among raft nodes before linearized reading' (duration: 134.052152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:49.710798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.098373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.710848Z","caller":"traceutil/trace.go:171","msg":"trace[1981471515] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:662; }","duration":"108.185186ms","start":"2026-02-04T05:09:49.602651Z","end":"2026-02-04T05:09:49.710836Z","steps":["trace[1981471515] 'agreement among raft nodes before linearized reading' (duration: 68.45859ms)","trace[1981471515] 'range keys from in-memory index tree' (duration: 39.641373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:49.71097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.061632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.710987Z","caller":"traceutil/trace.go:171","msg":"trace[300180676] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:662; }","duration":"108.097023ms","start":"2026-02-04T05:09:49.602885Z","end":"2026-02-04T05:09:49.710982Z","steps":["trace[300180676] 'agreement among raft nodes before linearized reading' (duration: 68.239333ms)","trace[300180676] 'count revisions from in-memory index tree' (duration: 39.83511ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:49.711087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.040262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:49.711105Z","caller":"traceutil/trace.go:171","msg":"trace[41537257] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:662; }","duration":"108.069312ms","start":"2026-02-04T05:09:49.603031Z","end":"2026-02-04T05:09:49.7111Z","steps":["trace[41537257] 'agreement among raft nodes before linearized reading' (duration: 68.16436ms)","trace[41537257] 'range keys from in-memory index tree' (duration: 39.883492ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:52.101641Z","caller":"traceutil/trace.go:171","msg":"trace[1773173742] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"184.671533ms","start":"2026-02-04T05:09:51.916956Z","end":"2026-02-04T05:09:52.101627Z","steps":["trace[1773173742] 'process raft request' (duration: 184.650932ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:52.101886Z","caller":"traceutil/trace.go:171","msg":"trace[1066907514] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"186.296528ms","start":"2026-02-04T05:09:51.915582Z","end":"2026-02-04T05:09:52.101879Z","steps":["trace[1066907514] 'process raft request' (duration: 126.114702ms)","trace[1066907514] 'compare' (duration: 59.812504ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:52.102013Z","caller":"traceutil/trace.go:171","msg":"trace[208105451] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"186.056159ms","start":"2026-02-04T05:09:51.915952Z","end":"2026-02-04T05:09:52.102008Z","steps":["trace[208105451] 'process raft request' (duration: 185.628995ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:54.020595Z","caller":"traceutil/trace.go:171","msg":"trace[1214234220] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"152.89729ms","start":"2026-02-04T05:09:53.867682Z","end":"2026-02-04T05:09:54.020579Z","steps":["trace[1214234220] 'read index received' (duration: 152.776446ms)","trace[1214234220] 'applied index is now lower than readState.Index' (duration: 120.284µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:54.020812Z","caller":"traceutil/trace.go:171","msg":"trace[477430196] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"196.37742ms","start":"2026-02-04T05:09:53.824429Z","end":"2026-02-04T05:09:54.020807Z","steps":["trace[477430196] 'process raft request' (duration: 196.07186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:54.021028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.304393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-04T05:09:54.021101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.187964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-04T05:09:54.021122Z","caller":"traceutil/trace.go:171","msg":"trace[1777514947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:692; }","duration":"113.224165ms","start":"2026-02-04T05:09:53.907892Z","end":"2026-02-04T05:09:54.021116Z","steps":["trace[1777514947] 'agreement among raft nodes before linearized reading' (duration: 113.179293ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:54.021111Z","caller":"traceutil/trace.go:171","msg":"trace[1452920789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:692; }","duration":"137.423476ms","start":"2026-02-04T05:09:53.883675Z","end":"2026-02-04T05:09:54.021098Z","steps":["trace[1452920789] 'agreement among raft nodes before linearized reading' (duration: 137.138197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:54.021051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.362034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2vb99\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-04T05:09:54.021339Z","caller":"traceutil/trace.go:171","msg":"trace[539360711] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2vb99; range_end:; response_count:1; response_revision:692; }","duration":"153.683695ms","start":"2026-02-04T05:09:53.867649Z","end":"2026-02-04T05:09:54.021333Z","steps":["trace[539360711] 'agreement among raft nodes before linearized reading' (duration: 153.324393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:54.222913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.642069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:09:54.223155Z","caller":"traceutil/trace.go:171","msg":"trace[1024698665] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"193.621285ms","start":"2026-02-04T05:09:54.029521Z","end":"2026-02-04T05:09:54.223143Z","steps":["trace[1024698665] 'process raft request' (duration: 30.695936ms)","trace[1024698665] 'compare' (duration: 162.565287ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:54.223566Z","caller":"traceutil/trace.go:171","msg":"trace[1402558381] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"118.159192ms","start":"2026-02-04T05:09:54.105401Z","end":"2026-02-04T05:09:54.22356Z","steps":["trace[1402558381] 'read index received' (duration: 118.062189ms)","trace[1402558381] 'applied index is now lower than readState.Index' (duration: 96.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:54.223685Z","caller":"traceutil/trace.go:171","msg":"trace[440501278] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"191.916699ms","start":"2026-02-04T05:09:54.031748Z","end":"2026-02-04T05:09:54.223665Z","steps":["trace[440501278] 'process raft request' (duration: 191.744073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:54.223783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.376829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:09:54.224091Z","caller":"traceutil/trace.go:171","msg":"trace[1210072578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:695; }","duration":"118.701589ms","start":"2026-02-04T05:09:54.105372Z","end":"2026-02-04T05:09:54.224074Z","steps":["trace[1210072578] 'agreement among raft nodes before linearized reading' (duration: 118.317276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:54.22417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.706189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:09:54.224202Z","caller":"traceutil/trace.go:171","msg":"trace[1555516881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:695; }","duration":"118.769972ms","start":"2026-02-04T05:09:54.105425Z","end":"2026-02-04T05:09:54.224195Z","steps":["trace[1555516881] 'agreement among raft nodes before linearized reading' (duration: 118.568325ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:54.223863Z","caller":"traceutil/trace.go:171","msg":"trace[797578794] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"181.25859ms","start":"2026-02-04T05:09:54.042598Z","end":"2026-02-04T05:09:54.223857Z","steps":["trace[797578794] 'process raft request' (duration: 180.9429ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:54.46059Z","caller":"traceutil/trace.go:171","msg":"trace[2146810469] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"215.289154ms","start":"2026-02-04T05:09:54.245287Z","end":"2026-02-04T05:09:54.460576Z","steps":["trace[2146810469] 'process raft request' (duration: 214.999305ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:54.461062Z","caller":"traceutil/trace.go:171","msg":"trace[10729530] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"219.502103ms","start":"2026-02-04T05:09:54.241553Z","end":"2026-02-04T05:09:54.461055Z","steps":["trace[10729530] 'process raft request' (duration: 149.636434ms)","trace[10729530] 'compare' (duration: 68.9953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:55.90325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.797488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:55.903307Z","caller":"traceutil/trace.go:171","msg":"trace[1474892754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"121.894432ms","start":"2026-02-04T05:09:55.7814Z","end":"2026-02-04T05:09:55.903294Z","steps":["trace[1474892754] 'range keys from in-memory index tree' (duration: 121.748086ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:55.903632Z","caller":"traceutil/trace.go:171","msg":"trace[296134201] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"121.187288ms","start":"2026-02-04T05:09:55.782375Z","end":"2026-02-04T05:09:55.903562Z","steps":["trace[296134201] 'process raft request' (duration: 120.760535ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.064087Z","caller":"traceutil/trace.go:171","msg":"trace[1402091288] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:739; }","duration":"156.863944ms","start":"2026-02-04T05:09:55.907175Z","end":"2026-02-04T05:09:56.064039Z","steps":["trace[1402091288] 'read index received' (duration: 111.893332ms)","trace[1402091288] 'applied index is now lower than readState.Index' (duration: 44.969772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:56.064215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.018888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:09:56.064222Z","caller":"traceutil/trace.go:171","msg":"trace[2116252808] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"262.683954ms","start":"2026-02-04T05:09:55.80152Z","end":"2026-02-04T05:09:56.064204Z","steps":["trace[2116252808] 'process raft request' (duration: 262.438146ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.064244Z","caller":"traceutil/trace.go:171","msg":"trace[69342118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"157.086841ms","start":"2026-02-04T05:09:55.907148Z","end":"2026-02-04T05:09:56.064235Z","steps":["trace[69342118] 'agreement among raft nodes before linearized reading' (duration: 156.998538ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.064355Z","caller":"traceutil/trace.go:171","msg":"trace[1877881320] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"262.879099ms","start":"2026-02-04T05:09:55.801469Z","end":"2026-02-04T05:09:56.064348Z","steps":["trace[1877881320] 'process raft request' (duration: 217.590147ms)","trace[1877881320] 'compare' (duration: 44.798167ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:56.064565Z","caller":"traceutil/trace.go:171","msg":"trace[1814392736] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"262.817278ms","start":"2026-02-04T05:09:55.801736Z","end":"2026-02-04T05:09:56.064554Z","steps":["trace[1814392736] 'process raft request' (duration: 262.26436ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.108756Z","caller":"traceutil/trace.go:171","msg":"trace[1567345898] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"196.452191ms","start":"2026-02-04T05:09:55.912289Z","end":"2026-02-04T05:09:56.108741Z","steps":["trace[1567345898] 'process raft request' (duration: 196.347097ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.259891Z","caller":"traceutil/trace.go:171","msg":"trace[1321855220] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:743; }","duration":"180.805268ms","start":"2026-02-04T05:09:56.079068Z","end":"2026-02-04T05:09:56.259873Z","steps":["trace[1321855220] 'read index received' (duration: 30.011462ms)","trace[1321855220] 'applied index is now lower than readState.Index' (duration: 150.792836ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:56.259963Z","caller":"traceutil/trace.go:171","msg":"trace[308443104] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"183.133531ms","start":"2026-02-04T05:09:56.076823Z","end":"2026-02-04T05:09:56.259957Z","steps":["trace[308443104] 'process raft request' (duration: 182.978337ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.260062Z","caller":"traceutil/trace.go:171","msg":"trace[701518981] transaction","detail":"{read_only:false; number_of_response:1; response_revision:723; }","duration":"179.895779ms","start":"2026-02-04T05:09:56.080161Z","end":"2026-02-04T05:09:56.260056Z","steps":["trace[701518981] 'process raft request' (duration: 179.677002ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.260208Z","caller":"traceutil/trace.go:171","msg":"trace[2105919579] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"183.784493ms","start":"2026-02-04T05:09:56.076402Z","end":"2026-02-04T05:09:56.260186Z","steps":["trace[2105919579] 'process raft request' (duration: 180.790097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:56.260256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.173259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-dffnr\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-04T05:09:56.260279Z","caller":"traceutil/trace.go:171","msg":"trace[1176411976] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-dffnr; range_end:; response_count:1; response_revision:723; }","duration":"181.220731ms","start":"2026-02-04T05:09:56.079052Z","end":"2026-02-04T05:09:56.260272Z","steps":["trace[1176411976] 'agreement among raft nodes before linearized reading' (duration: 181.120038ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.433624Z","caller":"traceutil/trace.go:171","msg":"trace[2082465962] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:746; }","duration":"173.667528ms","start":"2026-02-04T05:09:56.259937Z","end":"2026-02-04T05:09:56.433604Z","steps":["trace[2082465962] 'read index received' (duration: 139.243865ms)","trace[2082465962] 'applied index is now lower than readState.Index' (duration: 34.422793ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:56.433686Z","caller":"traceutil/trace.go:171","msg":"trace[648008530] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"315.91926ms","start":"2026-02-04T05:09:56.117762Z","end":"2026-02-04T05:09:56.433681Z","steps":["trace[648008530] 'process raft request' (duration: 315.785875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:56.433776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:56.117751Z","time spent":"315.963342ms","remote":"127.0.0.1:57952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:09:56.433825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.543644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T05:09:56.433857Z","caller":"traceutil/trace.go:171","msg":"trace[2107524847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:725; }","duration":"197.626137ms","start":"2026-02-04T05:09:56.236221Z","end":"2026-02-04T05:09:56.433848Z","steps":["trace[2107524847] 'agreement among raft nodes before linearized reading' (duration: 197.480202ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.433637Z","caller":"traceutil/trace.go:171","msg":"trace[335072598] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"321.176248ms","start":"2026-02-04T05:09:56.112432Z","end":"2026-02-04T05:09:56.433608Z","steps":["trace[335072598] 'process raft request' (duration: 286.775055ms)","trace[335072598] 'compare' (duration: 34.205347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:56.434111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.058489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-04T05:09:56.434134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:56.112418Z","time spent":"321.700165ms","remote":"127.0.0.1:57550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:09:56.434139Z","caller":"traceutil/trace.go:171","msg":"trace[1584151237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"207.111051ms","start":"2026-02-04T05:09:56.227019Z","end":"2026-02-04T05:09:56.43413Z","steps":["trace[1584151237] 'agreement among raft nodes before linearized reading' (duration: 207.029408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:56.484329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.164368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T05:09:56.484385Z","caller":"traceutil/trace.go:171","msg":"trace[1128410549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"205.24324ms","start":"2026-02-04T05:09:56.27913Z","end":"2026-02-04T05:09:56.484373Z","steps":["trace[1128410549] 'agreement among raft nodes before linearized reading' (duration: 205.059365ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.484537Z","caller":"traceutil/trace.go:171","msg":"trace[1351873803] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"213.397521ms","start":"2026-02-04T05:09:56.271134Z","end":"2026-02-04T05:09:56.484531Z","steps":["trace[1351873803] 'process raft request' (duration: 212.841164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:56.484677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.358607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-04T05:09:56.484696Z","caller":"traceutil/trace.go:171","msg":"trace[90018804] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:726; }","duration":"216.399158ms","start":"2026-02-04T05:09:56.268293Z","end":"2026-02-04T05:09:56.484692Z","steps":["trace[90018804] 'agreement among raft nodes before linearized reading' (duration: 216.349306ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.629541Z","caller":"traceutil/trace.go:171","msg":"trace[135496126] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"183.629648ms","start":"2026-02-04T05:09:56.445906Z","end":"2026-02-04T05:09:56.629536Z","steps":["trace[135496126] 'process raft request' (duration: 183.237856ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.629726Z","caller":"traceutil/trace.go:171","msg":"trace[826644792] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"183.626968ms","start":"2026-02-04T05:09:56.446094Z","end":"2026-02-04T05:09:56.629721Z","steps":["trace[826644792] 'process raft request' (duration: 183.189114ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.629907Z","caller":"traceutil/trace.go:171","msg":"trace[164281136] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"183.195015ms","start":"2026-02-04T05:09:56.446135Z","end":"2026-02-04T05:09:56.62933Z","steps":["trace[164281136] 'process raft request' (duration: 183.172874ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.63Z","caller":"traceutil/trace.go:171","msg":"trace[126715581] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:752; }","duration":"145.854327ms","start":"2026-02-04T05:09:56.48414Z","end":"2026-02-04T05:09:56.629995Z","steps":["trace[126715581] 'read index received' (duration: 145.852337ms)","trace[126715581] 'applied index is now lower than readState.Index' (duration: 1.68µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:56.6301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.411396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-04T05:09:56.630121Z","caller":"traceutil/trace.go:171","msg":"trace[1433812690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"161.470368ms","start":"2026-02-04T05:09:56.468646Z","end":"2026-02-04T05:09:56.630116Z","steps":["trace[1433812690] 'agreement among raft nodes before linearized reading' (duration: 161.385185ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.678394Z","caller":"traceutil/trace.go:171","msg":"trace[1356892030] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"180.57265ms","start":"2026-02-04T05:09:56.497806Z","end":"2026-02-04T05:09:56.678379Z","steps":["trace[1356892030] 'process raft request' (duration: 180.545789ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.678686Z","caller":"traceutil/trace.go:171","msg":"trace[75974701] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"184.827487ms","start":"2026-02-04T05:09:56.493854Z","end":"2026-02-04T05:09:56.678681Z","steps":["trace[75974701] 'process raft request' (duration: 184.348022ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.678787Z","caller":"traceutil/trace.go:171","msg":"trace[806739211] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"184.804406ms","start":"2026-02-04T05:09:56.493978Z","end":"2026-02-04T05:09:56.678782Z","steps":["trace[806739211] 'process raft request' (duration: 184.31175ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.678892Z","caller":"traceutil/trace.go:171","msg":"trace[1349807470] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"184.914669ms","start":"2026-02-04T05:09:56.493973Z","end":"2026-02-04T05:09:56.678887Z","steps":["trace[1349807470] 'process raft request' (duration: 184.339411ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.678947Z","caller":"traceutil/trace.go:171","msg":"trace[105793652] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"181.418117ms","start":"2026-02-04T05:09:56.497525Z","end":"2026-02-04T05:09:56.678943Z","steps":["trace[105793652] 'process raft request' (duration: 180.810638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:56.679172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.006284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T05:09:56.679198Z","caller":"traceutil/trace.go:171","msg":"trace[103976955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"166.072776ms","start":"2026-02-04T05:09:56.513119Z","end":"2026-02-04T05:09:56.679192Z","steps":["trace[103976955] 'agreement among raft nodes before linearized reading' (duration: 165.982513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:56.806504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.545048ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:09:56.806573Z","caller":"traceutil/trace.go:171","msg":"trace[661983059] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"117.013302ms","start":"2026-02-04T05:09:56.689546Z","end":"2026-02-04T05:09:56.806559Z","steps":["trace[661983059] 'read index received' (duration: 14.326199ms)","trace[661983059] 'applied index is now lower than readState.Index' (duration: 102.686463ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:09:56.806658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.110645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-gksqf\" ","response":"range_response_count:1 size:4812"} {"level":"info","ts":"2026-02-04T05:09:56.806674Z","caller":"traceutil/trace.go:171","msg":"trace[2075056747] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-gksqf; range_end:; response_count:1; response_revision:735; }","duration":"117.155077ms","start":"2026-02-04T05:09:56.689515Z","end":"2026-02-04T05:09:56.80667Z","steps":["trace[2075056747] 'agreement among raft nodes before linearized reading' (duration: 117.073754ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:09:56.806896Z","caller":"traceutil/trace.go:171","msg":"trace[1170393106] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"172.405968ms","start":"2026-02-04T05:09:56.634484Z","end":"2026-02-04T05:09:56.80689Z","steps":["trace[1170393106] 'process raft request' (duration: 69.419665ms)","trace[1170393106] 'compare' (duration: 102.474366ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:57.052967Z","caller":"traceutil/trace.go:171","msg":"trace[1287137712] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"359.093167ms","start":"2026-02-04T05:09:56.693852Z","end":"2026-02-04T05:09:57.052945Z","steps":["trace[1287137712] 'process raft request' (duration: 359.046166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:57.0531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:56.693844Z","time spent":"359.18573ms","remote":"127.0.0.1:57754","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-02-04T05:09:57.053193Z","caller":"traceutil/trace.go:171","msg":"trace[1172901022] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"360.739971ms","start":"2026-02-04T05:09:56.692436Z","end":"2026-02-04T05:09:57.053176Z","steps":["trace[1172901022] 'process raft request' (duration: 360.347188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:57.053277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:56.692423Z","time spent":"360.808132ms","remote":"127.0.0.1:57952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:09:57.162483Z","caller":"traceutil/trace.go:171","msg":"trace[1934210760] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"353.907983ms","start":"2026-02-04T05:09:56.808557Z","end":"2026-02-04T05:09:57.162465Z","steps":["trace[1934210760] 'process raft request' (duration: 344.576157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:57.162602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:56.808534Z","time spent":"354.015656ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4809,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:09:57.525372Z","caller":"traceutil/trace.go:171","msg":"trace[1564915461] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"350.493987ms","start":"2026-02-04T05:09:57.17486Z","end":"2026-02-04T05:09:57.525354Z","steps":["trace[1564915461] 'process raft request' (duration: 350.455646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:57.525473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:57.174854Z","time spent":"350.563229ms","remote":"127.0.0.1:57990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:09:57.525564Z","caller":"traceutil/trace.go:171","msg":"trace[705807052] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"353.735641ms","start":"2026-02-04T05:09:57.171809Z","end":"2026-02-04T05:09:57.525544Z","steps":["trace[705807052] 'process raft request' (duration: 298.004743ms)","trace[705807052] 'compare' (duration: 55.373517ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:09:57.525608Z","caller":"traceutil/trace.go:171","msg":"trace[2120497774] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"353.459551ms","start":"2026-02-04T05:09:57.172141Z","end":"2026-02-04T05:09:57.5256Z","steps":["trace[2120497774] 'process raft request' (duration: 353.139391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:09:57.526408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:57.172132Z","time spent":"353.491282ms","remote":"127.0.0.1:57648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:09:57.525645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:09:57.1718Z","time spent":"353.806023ms","remote":"127.0.0.1:57550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:09:59.826208Z","caller":"traceutil/trace.go:171","msg":"trace[916746543] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"105.907888ms","start":"2026-02-04T05:09:59.72029Z","end":"2026-02-04T05:09:59.826198Z","steps":["trace[916746543] 'process raft request' (duration: 105.681191ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:00.105399Z","caller":"traceutil/trace.go:171","msg":"trace[1374686395] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"143.639037ms","start":"2026-02-04T05:09:59.96174Z","end":"2026-02-04T05:10:00.105379Z","steps":["trace[1374686395] 'process raft request' (duration: 86.834591ms)","trace[1374686395] 'compare' (duration: 56.726763ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:10:04.928589Z","caller":"traceutil/trace.go:171","msg":"trace[1332152239] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"103.002043ms","start":"2026-02-04T05:10:04.825574Z","end":"2026-02-04T05:10:04.928576Z","steps":["trace[1332152239] 'process raft request' (duration: 80.583823ms)","trace[1332152239] 'compare' (duration: 21.966537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:10:49.268686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.342621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T05:10:49.268781Z","caller":"traceutil/trace.go:171","msg":"trace[897252612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"108.504405ms","start":"2026-02-04T05:10:49.160255Z","end":"2026-02-04T05:10:49.268759Z","steps":["trace[897252612] 'range keys from in-memory index tree' (duration: 108.181228ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:49.395126Z","caller":"traceutil/trace.go:171","msg":"trace[529171564] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"120.797686ms","start":"2026-02-04T05:10:49.274303Z","end":"2026-02-04T05:10:49.3951Z","steps":["trace[529171564] 'process raft request' (duration: 120.638553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:49.583574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.846625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:10:49.583645Z","caller":"traceutil/trace.go:171","msg":"trace[2022196002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:901; }","duration":"103.954216ms","start":"2026-02-04T05:10:49.479676Z","end":"2026-02-04T05:10:49.583631Z","steps":["trace[2022196002] 'range keys from in-memory index tree' (duration: 103.763413ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:49.940546Z","caller":"traceutil/trace.go:171","msg":"trace[1411651577] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"351.381182ms","start":"2026-02-04T05:10:49.589142Z","end":"2026-02-04T05:10:49.940523Z","steps":["trace[1411651577] 'process raft request' (duration: 351.2671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:49.940692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:10:49.589125Z","time spent":"351.487394ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:10:49.977093Z","caller":"traceutil/trace.go:171","msg":"trace[2088528724] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"335.555633ms","start":"2026-02-04T05:10:49.641514Z","end":"2026-02-04T05:10:49.977069Z","steps":["trace[2088528724] 'read index received' (duration: 299.608529ms)","trace[2088528724] 'applied index is now lower than readState.Index' (duration: 35.946174ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:10:49.977436Z","caller":"traceutil/trace.go:171","msg":"trace[1730633287] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"387.926018ms","start":"2026-02-04T05:10:49.589497Z","end":"2026-02-04T05:10:49.977423Z","steps":["trace[1730633287] 'process raft request' (duration: 387.471299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:49.977533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:10:49.589486Z","time spent":"387.98753ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:10:49.97778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.267867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T05:10:49.977816Z","caller":"traceutil/trace.go:171","msg":"trace[38087269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"336.303537ms","start":"2026-02-04T05:10:49.641504Z","end":"2026-02-04T05:10:49.977807Z","steps":["trace[38087269] 'agreement among raft nodes before linearized reading' (duration: 336.176305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:49.977851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:10:49.641464Z","time spent":"336.378529ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T05:10:49.977983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.763582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:10:49.978014Z","caller":"traceutil/trace.go:171","msg":"trace[305393022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"200.823244ms","start":"2026-02-04T05:10:49.777182Z","end":"2026-02-04T05:10:49.978006Z","steps":["trace[305393022] 'agreement among raft nodes before linearized reading' (duration: 200.781382ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:50.317454Z","caller":"traceutil/trace.go:171","msg":"trace[1962789129] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"101.381741ms","start":"2026-02-04T05:10:50.216057Z","end":"2026-02-04T05:10:50.317439Z","steps":["trace[1962789129] 'process raft request' (duration: 101.29167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:53.588524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.485336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T05:10:53.588617Z","caller":"traceutil/trace.go:171","msg":"trace[1248857039] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:911; }","duration":"235.633698ms","start":"2026-02-04T05:10:53.352969Z","end":"2026-02-04T05:10:53.588603Z","steps":["trace[1248857039] 'count revisions from in-memory index tree' (duration: 235.378113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:53.589592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.375054ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:10:53.589679Z","caller":"traceutil/trace.go:171","msg":"trace[1138302130] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:911; }","duration":"155.468345ms","start":"2026-02-04T05:10:53.434199Z","end":"2026-02-04T05:10:53.589667Z","steps":["trace[1138302130] 'range keys from in-memory index tree' (duration: 155.365714ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:53.589756Z","caller":"traceutil/trace.go:171","msg":"trace[1067871096] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"156.890691ms","start":"2026-02-04T05:10:53.432753Z","end":"2026-02-04T05:10:53.589644Z","steps":["trace[1067871096] 'read index received' (duration: 152.614379ms)","trace[1067871096] 'applied index is now lower than readState.Index' (duration: 4.274752ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:10:53.590573Z","caller":"traceutil/trace.go:171","msg":"trace[298928929] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"166.203738ms","start":"2026-02-04T05:10:53.424355Z","end":"2026-02-04T05:10:53.590558Z","steps":["trace[298928929] 'process raft request' (duration: 161.062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:53.591063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.287098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T05:10:53.591402Z","caller":"traceutil/trace.go:171","msg":"trace[1829585882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:912; }","duration":"158.659854ms","start":"2026-02-04T05:10:53.432727Z","end":"2026-02-04T05:10:53.591387Z","steps":["trace[1829585882] 'agreement among raft nodes before linearized reading' (duration: 158.213106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:53.591931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.534616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:10:53.592155Z","caller":"traceutil/trace.go:171","msg":"trace[1625074781] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:912; }","duration":"114.6889ms","start":"2026-02-04T05:10:53.477348Z","end":"2026-02-04T05:10:53.592036Z","steps":["trace[1625074781] 'agreement among raft nodes before linearized reading' (duration: 114.505166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:54.158657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.781909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:10:54.158718Z","caller":"traceutil/trace.go:171","msg":"trace[557660047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"205.880342ms","start":"2026-02-04T05:10:53.952824Z","end":"2026-02-04T05:10:54.158705Z","steps":["trace[557660047] 'range keys from in-memory index tree' (duration: 205.663537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:54.158785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.914787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:10:54.158796Z","caller":"traceutil/trace.go:171","msg":"trace[1416296022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"160.962857ms","start":"2026-02-04T05:10:53.997829Z","end":"2026-02-04T05:10:54.158792Z","steps":["trace[1416296022] 'range keys from in-memory index tree' (duration: 160.825315ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:55.601376Z","caller":"traceutil/trace.go:171","msg":"trace[530352729] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"466.733621ms","start":"2026-02-04T05:10:55.134624Z","end":"2026-02-04T05:10:55.601358Z","steps":["trace[530352729] 'read index received' (duration: 466.538147ms)","trace[530352729] 'applied index is now lower than readState.Index' (duration: 194.374µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:10:55.602127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.477934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T05:10:55.602369Z","caller":"traceutil/trace.go:171","msg":"trace[385267721] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:926; }","duration":"467.77545ms","start":"2026-02-04T05:10:55.134573Z","end":"2026-02-04T05:10:55.602349Z","steps":["trace[385267721] 'agreement among raft nodes before linearized reading' (duration: 467.24073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:55.602498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:10:55.134552Z","time spent":"467.928053ms","remote":"127.0.0.1:57576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-04T05:10:55.602886Z","caller":"traceutil/trace.go:171","msg":"trace[172813890] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"515.043544ms","start":"2026-02-04T05:10:55.087829Z","end":"2026-02-04T05:10:55.602873Z","steps":["trace[172813890] 'process raft request' (duration: 513.397114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:55.602399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.046295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-04T05:10:55.603059Z","caller":"traceutil/trace.go:171","msg":"trace[1109179766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"436.735978ms","start":"2026-02-04T05:10:55.166307Z","end":"2026-02-04T05:10:55.603043Z","steps":["trace[1109179766] 'agreement among raft nodes before linearized reading' (duration: 435.386823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:55.603313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:10:55.087808Z","time spent":"515.189017ms","remote":"127.0.0.1:37748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1317,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T05:10:55.603461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:10:55.166297Z","time spent":"436.881201ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T05:10:55.603927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.044755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0c8fe34-bf49-41fd-a550-1a816253390e\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-04T05:10:55.604125Z","caller":"traceutil/trace.go:171","msg":"trace[752885701] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0c8fe34-bf49-41fd-a550-1a816253390e; range_end:; response_count:1; response_revision:926; }","duration":"107.268008ms","start":"2026-02-04T05:10:55.496843Z","end":"2026-02-04T05:10:55.604111Z","steps":["trace[752885701] 'agreement among raft nodes before linearized reading' (duration: 106.980863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:55.604557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.892545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:10:55.604605Z","caller":"traceutil/trace.go:171","msg":"trace[905042959] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:926; }","duration":"158.975017ms","start":"2026-02-04T05:10:55.445619Z","end":"2026-02-04T05:10:55.604594Z","steps":["trace[905042959] 'agreement among raft nodes before linearized reading' (duration: 158.896046ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:55.735525Z","caller":"traceutil/trace.go:171","msg":"trace[194743765] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"126.57695ms","start":"2026-02-04T05:10:55.608916Z","end":"2026-02-04T05:10:55.735493Z","steps":["trace[194743765] 'process raft request' (duration: 90.598216ms)","trace[194743765] 'compare' (duration: 35.872012ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:10:55.736587Z","caller":"traceutil/trace.go:171","msg":"trace[1956160253] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"122.357582ms","start":"2026-02-04T05:10:55.614216Z","end":"2026-02-04T05:10:55.736574Z","steps":["trace[1956160253] 'process raft request' (duration: 122.136048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:55.948683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.224072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:10:55.948922Z","caller":"traceutil/trace.go:171","msg":"trace[603040554] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"172.319137ms","start":"2026-02-04T05:10:55.776589Z","end":"2026-02-04T05:10:55.948908Z","steps":["trace[603040554] 'read index received' (duration: 59.742479ms)","trace[603040554] 'applied index is now lower than readState.Index' (duration: 112.575558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:10:55.949067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.46454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:10:55.949156Z","caller":"traceutil/trace.go:171","msg":"trace[1939330269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"172.589772ms","start":"2026-02-04T05:10:55.776554Z","end":"2026-02-04T05:10:55.949144Z","steps":["trace[1939330269] 'agreement among raft nodes before linearized reading' (duration: 172.46602ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:55.949074Z","caller":"traceutil/trace.go:171","msg":"trace[253169325] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"203.96724ms","start":"2026-02-04T05:10:55.745084Z","end":"2026-02-04T05:10:55.949051Z","steps":["trace[253169325] 'process raft request' (duration: 91.2993ms)","trace[253169325] 'compare' (duration: 112.12102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:10:56.879005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.751293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:10:56.879116Z","caller":"traceutil/trace.go:171","msg":"trace[1255847933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"100.904926ms","start":"2026-02-04T05:10:56.778186Z","end":"2026-02-04T05:10:56.879091Z","steps":["trace[1255847933] 'range keys from in-memory index tree' (duration: 100.693653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:56.879259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.756389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T05:10:56.879313Z","caller":"traceutil/trace.go:171","msg":"trace[226027483] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:933; }","duration":"103.844251ms","start":"2026-02-04T05:10:56.775453Z","end":"2026-02-04T05:10:56.879298Z","steps":["trace[226027483] 'count revisions from in-memory index tree' (duration: 103.697468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:10:56.879051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.032493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0c8fe34-bf49-41fd-a550-1a816253390e\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-04T05:10:56.879582Z","caller":"traceutil/trace.go:171","msg":"trace[324762629] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0c8fe34-bf49-41fd-a550-1a816253390e; range_end:; response_count:1; response_revision:933; }","duration":"268.616194ms","start":"2026-02-04T05:10:56.610944Z","end":"2026-02-04T05:10:56.879561Z","steps":["trace[324762629] 'range keys from in-memory index tree' (duration: 267.88505ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:10:58.341196Z","caller":"traceutil/trace.go:171","msg":"trace[2396287] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"329.645951ms","start":"2026-02-04T05:10:58.011518Z","end":"2026-02-04T05:10:58.341164Z","steps":["trace[2396287] 'process raft request' (duration: 302.945861ms)","trace[2396287] 'compare' (duration: 25.09938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:10:58.341294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:10:58.011503Z","time spent":"329.746552ms","remote":"127.0.0.1:57550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":852,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T05:11:01.152983Z","caller":"traceutil/trace.go:171","msg":"trace[184209940] transaction","detail":"{read_only:false; number_of_response:1; response_revision:952; }","duration":"200.979802ms","start":"2026-02-04T05:11:00.95198Z","end":"2026-02-04T05:11:01.15296Z","steps":["trace[184209940] 'process raft request' (duration: 199.981394ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:01.268075Z","caller":"traceutil/trace.go:171","msg":"trace[1921778590] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"106.110844ms","start":"2026-02-04T05:11:01.161939Z","end":"2026-02-04T05:11:01.26805Z","steps":["trace[1921778590] 'process raft request' (duration: 92.779915ms)","trace[1921778590] 'compare' (duration: 13.240938ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:02.19267Z","caller":"traceutil/trace.go:171","msg":"trace[1733839700] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"169.406749ms","start":"2026-02-04T05:11:02.023245Z","end":"2026-02-04T05:11:02.192652Z","steps":["trace[1733839700] 'read index received' (duration: 144.139469ms)","trace[1733839700] 'applied index is now lower than readState.Index' (duration: 25.2666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:11:02.193089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.821656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T05:11:02.193294Z","caller":"traceutil/trace.go:171","msg":"trace[696368416] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"255.081903ms","start":"2026-02-04T05:11:01.938189Z","end":"2026-02-04T05:11:02.193271Z","steps":["trace[696368416] 'process raft request' (duration: 229.131041ms)","trace[696368416] 'compare' (duration: 25.24146ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:02.193138Z","caller":"traceutil/trace.go:171","msg":"trace[1756443669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"169.914767ms","start":"2026-02-04T05:11:02.023211Z","end":"2026-02-04T05:11:02.193126Z","steps":["trace[1756443669] 'agreement among raft nodes before linearized reading' (duration: 169.50544ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:03.912997Z","caller":"traceutil/trace.go:171","msg":"trace[697167763] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"121.51263ms","start":"2026-02-04T05:11:03.791464Z","end":"2026-02-04T05:11:03.912976Z","steps":["trace[697167763] 'process raft request' (duration: 121.379468ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:12.224234Z","caller":"traceutil/trace.go:171","msg":"trace[426849316] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"280.76543ms","start":"2026-02-04T05:11:11.943442Z","end":"2026-02-04T05:11:12.224207Z","steps":["trace[426849316] 'process raft request' (duration: 280.500126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:12.662999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.39576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:11:12.663298Z","caller":"traceutil/trace.go:171","msg":"trace[829743107] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1000; }","duration":"159.753765ms","start":"2026-02-04T05:11:12.503524Z","end":"2026-02-04T05:11:12.663277Z","steps":["trace[829743107] 'count revisions from in-memory index tree' (duration: 159.277117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:12.66389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.024723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-04T05:11:12.664034Z","caller":"traceutil/trace.go:171","msg":"trace[1273596733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"331.199166ms","start":"2026-02-04T05:11:12.332821Z","end":"2026-02-04T05:11:12.66402Z","steps":["trace[1273596733] 'range keys from in-memory index tree' (duration: 330.85675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:12.664167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:11:12.332805Z","time spent":"331.346308ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T05:11:12.667398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.910864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-04T05:11:12.667728Z","caller":"traceutil/trace.go:171","msg":"trace[342409105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"140.27189ms","start":"2026-02-04T05:11:12.527446Z","end":"2026-02-04T05:11:12.667718Z","steps":["trace[342409105] 'range keys from in-memory index tree' (duration: 139.804962ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:12.816588Z","caller":"traceutil/trace.go:171","msg":"trace[928001925] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1042; }","duration":"123.803225ms","start":"2026-02-04T05:11:12.692761Z","end":"2026-02-04T05:11:12.816564Z","steps":["trace[928001925] 'read index received' (duration: 76.705759ms)","trace[928001925] 'applied index is now lower than readState.Index' (duration: 47.096516ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:12.817162Z","caller":"traceutil/trace.go:171","msg":"trace[1046140821] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"135.843345ms","start":"2026-02-04T05:11:12.681282Z","end":"2026-02-04T05:11:12.817125Z","steps":["trace[1046140821] 'process raft request' (duration: 135.080273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:12.818049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.919583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:11:12.818967Z","caller":"traceutil/trace.go:171","msg":"trace[1103536029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"107.859409ms","start":"2026-02-04T05:11:12.711086Z","end":"2026-02-04T05:11:12.818945Z","steps":["trace[1103536029] 'agreement among raft nodes before linearized reading' (duration: 106.550958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:12.818398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.210679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:11:12.819225Z","caller":"traceutil/trace.go:171","msg":"trace[220373239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"126.483691ms","start":"2026-02-04T05:11:12.692731Z","end":"2026-02-04T05:11:12.819214Z","steps":["trace[220373239] 'agreement among raft nodes before linearized reading' (duration: 125.023937ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:13.322183Z","caller":"traceutil/trace.go:171","msg":"trace[266928068] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"497.853693ms","start":"2026-02-04T05:11:12.824273Z","end":"2026-02-04T05:11:13.322127Z","steps":["trace[266928068] 'process raft request' (duration: 497.727541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:13.322327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:11:12.824264Z","time spent":"497.984045ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T05:11:13.378598Z","caller":"traceutil/trace.go:171","msg":"trace[131580773] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"552.931325ms","start":"2026-02-04T05:11:12.825648Z","end":"2026-02-04T05:11:13.37858Z","steps":["trace[131580773] 'process raft request' (duration: 552.846144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:13.37871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:11:12.82563Z","time spent":"553.017147ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:11:13.675566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.964334ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:11:13.675999Z","caller":"traceutil/trace.go:171","msg":"trace[932824783] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1004; }","duration":"241.403281ms","start":"2026-02-04T05:11:13.434574Z","end":"2026-02-04T05:11:13.675977Z","steps":["trace[932824783] 'range keys from in-memory index tree' (duration: 240.948954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:14.532588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.829928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:11:14.532664Z","caller":"traceutil/trace.go:171","msg":"trace[58991442] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"258.53897ms","start":"2026-02-04T05:11:14.274113Z","end":"2026-02-04T05:11:14.532652Z","steps":["trace[58991442] 'process raft request' (duration: 156.58105ms)","trace[58991442] 'compare' (duration: 101.413491ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:18.882781Z","caller":"traceutil/trace.go:171","msg":"trace[214260052] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"235.776222ms","start":"2026-02-04T05:11:18.646987Z","end":"2026-02-04T05:11:18.882763Z","steps":["trace[214260052] 'read index received' (duration: 235.503208ms)","trace[214260052] 'applied index is now lower than readState.Index' (duration: 271.634µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:11:18.883224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.226989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2025"} {"level":"info","ts":"2026-02-04T05:11:18.883333Z","caller":"traceutil/trace.go:171","msg":"trace[1955680021] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:1020; }","duration":"236.367822ms","start":"2026-02-04T05:11:18.646953Z","end":"2026-02-04T05:11:18.88332Z","steps":["trace[1955680021] 'agreement among raft nodes before linearized reading' (duration: 236.21003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:18.883531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.211938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T05:11:18.883574Z","caller":"traceutil/trace.go:171","msg":"trace[1638645219] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1020; }","duration":"185.28789ms","start":"2026-02-04T05:11:18.698277Z","end":"2026-02-04T05:11:18.883564Z","steps":["trace[1638645219] 'agreement among raft nodes before linearized reading' (duration: 185.196909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:18.886632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.728368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:11:18.886748Z","caller":"traceutil/trace.go:171","msg":"trace[1952533959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"108.884451ms","start":"2026-02-04T05:11:18.77785Z","end":"2026-02-04T05:11:18.886735Z","steps":["trace[1952533959] 'agreement among raft nodes before linearized reading' (duration: 108.745569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.236965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.177575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:11:19.237214Z","caller":"traceutil/trace.go:171","msg":"trace[1559069840] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"309.13371ms","start":"2026-02-04T05:11:18.928068Z","end":"2026-02-04T05:11:19.237202Z","steps":["trace[1559069840] 'read index received' (duration: 64.619349ms)","trace[1559069840] 'applied index is now lower than readState.Index' (duration: 244.513641ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:19.237491Z","caller":"traceutil/trace.go:171","msg":"trace[1585166320] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"343.848587ms","start":"2026-02-04T05:11:18.893632Z","end":"2026-02-04T05:11:19.237481Z","steps":["trace[1585166320] 'process raft request' (duration: 99.104973ms)","trace[1585166320] 'compare' (duration: 244.103544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:11:19.237598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:11:18.893625Z","time spent":"343.894109ms","remote":"127.0.0.1:57754","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-02-04T05:11:19.237784Z","caller":"traceutil/trace.go:171","msg":"trace[214074730] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"344.094512ms","start":"2026-02-04T05:11:18.89368Z","end":"2026-02-04T05:11:19.237775Z","steps":["trace[214074730] 'process raft request' (duration: 343.36613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.237903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:11:18.893662Z","time spent":"344.146572ms","remote":"127.0.0.1:57754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T05:11:19.238076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.488138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T05:11:19.238105Z","caller":"traceutil/trace.go:171","msg":"trace[671796693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"344.522949ms","start":"2026-02-04T05:11:18.893575Z","end":"2026-02-04T05:11:19.238098Z","steps":["trace[671796693] 'agreement among raft nodes before linearized reading' (duration: 344.477068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.238235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:11:18.893573Z","time spent":"344.60101ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T05:11:19.238417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.840125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-04T05:11:19.238443Z","caller":"traceutil/trace.go:171","msg":"trace[1730222304] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1023; }","duration":"344.880636ms","start":"2026-02-04T05:11:18.893555Z","end":"2026-02-04T05:11:19.238436Z","steps":["trace[1730222304] 'agreement among raft nodes before linearized reading' (duration: 344.828815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.238527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:11:18.893549Z","time spent":"344.967837ms","remote":"127.0.0.1:57850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":459,"request content":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" "} {"level":"warn","ts":"2026-02-04T05:11:19.238728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.579685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-04T05:11:19.238755Z","caller":"traceutil/trace.go:171","msg":"trace[434593942] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1023; }","duration":"307.638446ms","start":"2026-02-04T05:11:18.931111Z","end":"2026-02-04T05:11:19.238749Z","steps":["trace[434593942] 'agreement among raft nodes before linearized reading' (duration: 307.590755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.238866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T05:11:18.931057Z","time spent":"307.765288ms","remote":"127.0.0.1:57648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-04T05:11:19.239148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.312622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:1 size:1930"} {"level":"info","ts":"2026-02-04T05:11:19.239177Z","caller":"traceutil/trace.go:171","msg":"trace[411969807] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:1; response_revision:1023; }","duration":"205.369033ms","start":"2026-02-04T05:11:19.033801Z","end":"2026-02-04T05:11:19.23917Z","steps":["trace[411969807] 'agreement among raft nodes before linearized reading' (duration: 205.315963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.537728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.218546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-04T05:11:19.538535Z","caller":"traceutil/trace.go:171","msg":"trace[2089501778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"192.053321ms","start":"2026-02-04T05:11:19.346466Z","end":"2026-02-04T05:11:19.538519Z","steps":["trace[2089501778] 'range keys from in-memory index tree' (duration: 191.104205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.538276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.964787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-02-04T05:11:19.539481Z","caller":"traceutil/trace.go:171","msg":"trace[891477727] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:1; response_revision:1023; }","duration":"176.192237ms","start":"2026-02-04T05:11:19.363276Z","end":"2026-02-04T05:11:19.539468Z","steps":["trace[891477727] 'range keys from in-memory index tree' (duration: 174.873416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.538419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.861552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-02-04T05:11:19.539654Z","caller":"traceutil/trace.go:171","msg":"trace[2064573951] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1023; }","duration":"152.116421ms","start":"2026-02-04T05:11:19.387526Z","end":"2026-02-04T05:11:19.539643Z","steps":["trace[2064573951] 'range keys from in-memory index tree' (duration: 150.807091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.538486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.483334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-04T05:11:19.539895Z","caller":"traceutil/trace.go:171","msg":"trace[2045839992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"139.909856ms","start":"2026-02-04T05:11:19.399977Z","end":"2026-02-04T05:11:19.539887Z","steps":["trace[2045839992] 'range keys from in-memory index tree' (duration: 138.407013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.848066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.019567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T05:11:19.848159Z","caller":"traceutil/trace.go:171","msg":"trace[1139305978] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"214.057121ms","start":"2026-02-04T05:11:19.634082Z","end":"2026-02-04T05:11:19.848139Z","steps":["trace[1139305978] 'read index received' (duration: 13.854001ms)","trace[1139305978] 'applied index is now lower than readState.Index' (duration: 200.20208ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:19.848623Z","caller":"traceutil/trace.go:171","msg":"trace[1086860306] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"297.927803ms","start":"2026-02-04T05:11:19.55068Z","end":"2026-02-04T05:11:19.848608Z","steps":["trace[1086860306] 'process raft request' (duration: 97.294036ms)","trace[1086860306] 'compare' (duration: 199.919715ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:11:19.849047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.956316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-02-04T05:11:19.849092Z","caller":"traceutil/trace.go:171","msg":"trace[1975028275] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:1; response_revision:1025; }","duration":"215.024818ms","start":"2026-02-04T05:11:19.634054Z","end":"2026-02-04T05:11:19.849079Z","steps":["trace[1975028275] 'agreement among raft nodes before linearized reading' (duration: 214.895496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:19.849338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.283562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-02-04T05:11:19.849394Z","caller":"traceutil/trace.go:171","msg":"trace[468859713] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1025; }","duration":"195.379364ms","start":"2026-02-04T05:11:19.654006Z","end":"2026-02-04T05:11:19.849385Z","steps":["trace[468859713] 'agreement among raft nodes before linearized reading' (duration: 195.279872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:20.069276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.69834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-02-04T05:11:20.069408Z","caller":"traceutil/trace.go:171","msg":"trace[4517043] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:1025; }","duration":"106.888072ms","start":"2026-02-04T05:11:19.962505Z","end":"2026-02-04T05:11:20.069393Z","steps":["trace[4517043] 'range keys from in-memory index tree' (duration: 106.634709ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:21.012407Z","caller":"traceutil/trace.go:171","msg":"trace[423022990] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"154.426673ms","start":"2026-02-04T05:11:20.857955Z","end":"2026-02-04T05:11:21.012382Z","steps":["trace[423022990] 'read index received' (duration: 154.207109ms)","trace[423022990] 'applied index is now lower than readState.Index' (duration: 218.504µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:21.012539Z","caller":"traceutil/trace.go:171","msg":"trace[483725774] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"175.836654ms","start":"2026-02-04T05:11:20.836686Z","end":"2026-02-04T05:11:21.012523Z","steps":["trace[483725774] 'process raft request' (duration: 175.54729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:21.01275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.555525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-02-04T05:11:21.01281Z","caller":"traceutil/trace.go:171","msg":"trace[412106694] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:1027; }","duration":"142.647216ms","start":"2026-02-04T05:11:20.870143Z","end":"2026-02-04T05:11:21.01279Z","steps":["trace[412106694] 'agreement among raft nodes before linearized reading' (duration: 142.537544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:21.013033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.071864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-04T05:11:21.013085Z","caller":"traceutil/trace.go:171","msg":"trace[1138402033] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1027; }","duration":"155.144944ms","start":"2026-02-04T05:11:20.857923Z","end":"2026-02-04T05:11:21.013068Z","steps":["trace[1138402033] 'agreement among raft nodes before linearized reading' (duration: 155.057683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:21.155106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.958072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-02-04T05:11:21.156205Z","caller":"traceutil/trace.go:171","msg":"trace[708687136] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:1027; }","duration":"134.082949ms","start":"2026-02-04T05:11:21.022098Z","end":"2026-02-04T05:11:21.156181Z","steps":["trace[708687136] 'range keys from in-memory index tree' (duration: 132.890141ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:23.446885Z","caller":"traceutil/trace.go:171","msg":"trace[505314284] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"150.42391ms","start":"2026-02-04T05:11:23.296439Z","end":"2026-02-04T05:11:23.446863Z","steps":["trace[505314284] 'read index received' (duration: 150.283568ms)","trace[505314284] 'applied index is now lower than readState.Index' (duration: 139.402µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:23.447022Z","caller":"traceutil/trace.go:171","msg":"trace[866799959] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"168.605484ms","start":"2026-02-04T05:11:23.278404Z","end":"2026-02-04T05:11:23.44701Z","steps":["trace[866799959] 'process raft request' (duration: 168.321779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:23.447424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.972879ms","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-02-04T05:11:23.447472Z","caller":"traceutil/trace.go:171","msg":"trace[1987621762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"151.04505ms","start":"2026-02-04T05:11:23.296414Z","end":"2026-02-04T05:11:23.447459Z","steps":["trace[1987621762] 'agreement among raft nodes before linearized reading' (duration: 150.921378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:23.447748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.557272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:843"} {"level":"info","ts":"2026-02-04T05:11:23.447793Z","caller":"traceutil/trace.go:171","msg":"trace[1924228914] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1033; }","duration":"134.628543ms","start":"2026-02-04T05:11:23.313153Z","end":"2026-02-04T05:11:23.447781Z","steps":["trace[1924228914] 'agreement among raft nodes before linearized reading' (duration: 134.557872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:23.448195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.848533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-02-04T05:11:23.448238Z","caller":"traceutil/trace.go:171","msg":"trace[282022643] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1033; }","duration":"112.925944ms","start":"2026-02-04T05:11:23.335302Z","end":"2026-02-04T05:11:23.448228Z","steps":["trace[282022643] 'agreement among raft nodes before linearized reading' (duration: 112.850653ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:25.655566Z","caller":"traceutil/trace.go:171","msg":"trace[972966817] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"158.652051ms","start":"2026-02-04T05:11:25.496896Z","end":"2026-02-04T05:11:25.655548Z","steps":["trace[972966817] 'read index received' (duration: 69.545745ms)","trace[972966817] 'applied index is now lower than readState.Index' (duration: 89.105376ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T05:11:25.65584Z","caller":"traceutil/trace.go:171","msg":"trace[1546024847] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"181.26379ms","start":"2026-02-04T05:11:25.474562Z","end":"2026-02-04T05:11:25.655825Z","steps":["trace[1546024847] 'process raft request' (duration: 91.943761ms)","trace[1546024847] 'compare' (duration: 88.945683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:11:25.656028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.116078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:830"} {"level":"info","ts":"2026-02-04T05:11:25.656062Z","caller":"traceutil/trace.go:171","msg":"trace[827937432] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:1040; }","duration":"159.182758ms","start":"2026-02-04T05:11:25.496868Z","end":"2026-02-04T05:11:25.65605Z","steps":["trace[827937432] 'agreement among raft nodes before linearized reading' (duration: 159.112717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:25.656217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.620117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:843"} {"level":"info","ts":"2026-02-04T05:11:25.656244Z","caller":"traceutil/trace.go:171","msg":"trace[270081615] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1040; }","duration":"128.674918ms","start":"2026-02-04T05:11:25.527561Z","end":"2026-02-04T05:11:25.656236Z","steps":["trace[270081615] 'agreement among raft nodes before linearized reading' (duration: 128.628817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:29.096189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.451185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:1 size:715"} {"level":"info","ts":"2026-02-04T05:11:29.096239Z","caller":"traceutil/trace.go:171","msg":"trace[1187919473] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"124.542036ms","start":"2026-02-04T05:11:28.971685Z","end":"2026-02-04T05:11:29.096227Z","steps":["trace[1187919473] 'range keys from in-memory index tree' (duration: 124.394713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:29.09636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.545881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" ","response":"range_response_count:1 size:1488"} {"level":"info","ts":"2026-02-04T05:11:29.096372Z","caller":"traceutil/trace.go:171","msg":"trace[1655644001] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:1; response_revision:1049; }","duration":"100.576961ms","start":"2026-02-04T05:11:28.995791Z","end":"2026-02-04T05:11:29.096368Z","steps":["trace[1655644001] 'range keys from in-memory index tree' (duration: 100.498141ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:29.945836Z","caller":"traceutil/trace.go:171","msg":"trace[1125001604] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"243.296668ms","start":"2026-02-04T05:11:29.702523Z","end":"2026-02-04T05:11:29.94582Z","steps":["trace[1125001604] 'process raft request' (duration: 243.176077ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:29.946835Z","caller":"traceutil/trace.go:171","msg":"trace[135984008] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"240.523816ms","start":"2026-02-04T05:11:29.706305Z","end":"2026-02-04T05:11:29.946829Z","steps":["trace[135984008] 'process raft request' (duration: 240.382824ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T05:11:29.946737Z","caller":"traceutil/trace.go:171","msg":"trace[512182459] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1097; }","duration":"172.114212ms","start":"2026-02-04T05:11:29.774614Z","end":"2026-02-04T05:11:29.946728Z","steps":["trace[512182459] 'read index received' (duration: 171.159768ms)","trace[512182459] 'applied index is now lower than readState.Index' (duration: 953.944µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T05:11:29.946928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.282124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-02-04T05:11:29.948689Z","caller":"traceutil/trace.go:171","msg":"trace[576369368] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1052; }","duration":"174.071041ms","start":"2026-02-04T05:11:29.774587Z","end":"2026-02-04T05:11:29.948658Z","steps":["trace[576369368] 'agreement among raft nodes before linearized reading' (duration: 172.285264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T05:11:29.948635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.06647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-02-04T05:11:29.948922Z","caller":"traceutil/trace.go:171","msg":"trace[350568896] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:1052; }","duration":"158.358585ms","start":"2026-02-04T05:11:29.790538Z","end":"2026-02-04T05:11:29.948897Z","steps":["trace[350568896] 'agreement among raft nodes before linearized reading' (duration: 157.37794ms)"],"step_count":1}