{"level":"warn","ts":"2026-02-09T03:13:34.073916Z","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-09T03:13:34.074002Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-09T03:13:34.074261Z","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-09T03:13:34.074276Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-02-09T03:13:34.074383Z","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-09T03:13:34.075017Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-02-09T03:13:34.075169Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-09T03:13:34.078245Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.819229ms"} {"level":"info","ts":"2026-02-09T03:13:34.082937Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-02-09T03:13:34.083039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T03:13:34.083102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-02-09T03:13:34.083116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T03:13:34.083168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-02-09T03:13:34.083247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-02-09T03:13:34.089615Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T03:13:34.096345Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T03:13:34.097638Z","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-09T03:13:34.099628Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T03:13:34.101503Z","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-09T03:13:34.1017Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T03:13:34.101722Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T03:13:34.10188Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T03:13:34.102069Z","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-09T03:13:34.102099Z","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-09T03:13:34.102105Z","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-09T03:13:34.102323Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-02-09T03:13:34.102409Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-02-09T03:13:34.103997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-02-09T03:13:34.104141Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-02-09T03:13:34.884614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T03:13:34.884817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T03:13:34.884849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-02-09T03:13:34.884912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-02-09T03:13:34.884923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-02-09T03:13:34.884932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-02-09T03:13:34.88494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-02-09T03:13:34.889051Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T03:13:34.891592Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:13:34.891733Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T03:13:34.891997Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T03:13:34.893394Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T03:13:34.894491Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-02-09T03:13:34.903599Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T03:13:34.90362Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T03:13:34.925423Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:13:34.925517Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:13:34.925538Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T03:13:49.764699Z","caller":"traceutil/trace.go:171","msg":"trace[1632232015] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"288.90291ms","start":"2026-02-09T03:13:49.475774Z","end":"2026-02-09T03:13:49.764677Z","steps":["trace[1632232015] 'process raft request' (duration: 288.787409ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:13:50.181766Z","caller":"traceutil/trace.go:171","msg":"trace[10300847] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"411.614115ms","start":"2026-02-09T03:13:49.770116Z","end":"2026-02-09T03:13:50.18173Z","steps":["trace[10300847] 'process raft request' (duration: 409.970255ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:13:50.182027Z","caller":"traceutil/trace.go:171","msg":"trace[347759444] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"196.271965ms","start":"2026-02-09T03:13:49.985409Z","end":"2026-02-09T03:13:50.181681Z","steps":["trace[347759444] 'process raft request' (duration: 196.225225ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:13:50.182386Z","caller":"traceutil/trace.go:171","msg":"trace[526680161] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"371.245629ms","start":"2026-02-09T03:13:49.811132Z","end":"2026-02-09T03:13:50.182378Z","steps":["trace[526680161] 'read index received' (duration: 368.961917ms)","trace[526680161] 'applied index is now lower than readState.Index' (duration: 2.282983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:13:50.182467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.33912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:13:50.182492Z","caller":"traceutil/trace.go:171","msg":"trace[455924221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"371.39286ms","start":"2026-02-09T03:13:49.811093Z","end":"2026-02-09T03:13:50.182486Z","steps":["trace[455924221] 'agreement among raft nodes before linearized reading' (duration: 371.3523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:13:50.18251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:13:49.811066Z","time spent":"371.44054ms","remote":"127.0.0.1:38432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T03:13:50.182676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.751382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-09T03:13:50.182701Z","caller":"traceutil/trace.go:171","msg":"trace[1503609701] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:367; }","duration":"139.801622ms","start":"2026-02-09T03:13:50.042892Z","end":"2026-02-09T03:13:50.182694Z","steps":["trace[1503609701] 'agreement among raft nodes before linearized reading' (duration: 139.761902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:13:50.182167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:13:49.770098Z","time spent":"411.720755ms","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4575,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:13:58.301371Z","caller":"traceutil/trace.go:171","msg":"trace[696145882] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"142.000184ms","start":"2026-02-09T03:13:58.159352Z","end":"2026-02-09T03:13:58.301352Z","steps":["trace[696145882] 'process raft request' (duration: 127.58513ms)","trace[696145882] 'compare' (duration: 14.324854ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:01.771988Z","caller":"traceutil/trace.go:171","msg":"trace[1877490251] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"151.277947ms","start":"2026-02-09T03:14:01.620686Z","end":"2026-02-09T03:14:01.771964Z","steps":["trace[1877490251] 'process raft request' (duration: 144.835604ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:01.991074Z","caller":"traceutil/trace.go:171","msg":"trace[957971677] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"177.6873ms","start":"2026-02-09T03:14:01.813363Z","end":"2026-02-09T03:14:01.99105Z","steps":["trace[957971677] 'process raft request' (duration: 176.923276ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:01.999612Z","caller":"traceutil/trace.go:171","msg":"trace[655440604] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:441; }","duration":"162.112582ms","start":"2026-02-09T03:14:01.837487Z","end":"2026-02-09T03:14:01.9996Z","steps":["trace[655440604] 'read index received' (duration: 152.918476ms)","trace[655440604] 'applied index is now lower than readState.Index' (duration: 9.193076ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:01.999828Z","caller":"traceutil/trace.go:171","msg":"trace[1761629193] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"186.276874ms","start":"2026-02-09T03:14:01.81351Z","end":"2026-02-09T03:14:01.999787Z","steps":["trace[1761629193] 'process raft request' (duration: 186.009863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:02.000126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.603025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:14:02.001077Z","caller":"traceutil/trace.go:171","msg":"trace[1136422610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"163.165847ms","start":"2026-02-09T03:14:01.837437Z","end":"2026-02-09T03:14:02.000603Z","steps":["trace[1136422610] 'agreement among raft nodes before linearized reading' (duration: 162.474464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:02.346734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.998029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:14:02.347172Z","caller":"traceutil/trace.go:171","msg":"trace[736546253] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:443; }","duration":"243.096453ms","start":"2026-02-09T03:14:02.104004Z","end":"2026-02-09T03:14:02.347101Z","steps":["trace[736546253] 'read index received' (duration: 117.2193ms)","trace[736546253] 'applied index is now lower than readState.Index' (duration: 125.875543ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:02.347947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.891737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:02.34804Z","caller":"traceutil/trace.go:171","msg":"trace[64461410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"244.037647ms","start":"2026-02-09T03:14:02.103945Z","end":"2026-02-09T03:14:02.347983Z","steps":["trace[64461410] 'agreement among raft nodes before linearized reading' (duration: 243.519694ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:02.349328Z","caller":"traceutil/trace.go:171","msg":"trace[1179523724] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"344.573484ms","start":"2026-02-09T03:14:02.004737Z","end":"2026-02-09T03:14:02.349311Z","steps":["trace[1179523724] 'process raft request' (duration: 216.474369ms)","trace[1179523724] 'compare' (duration: 124.625278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:02.349437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:02.00471Z","time spent":"344.656674ms","remote":"127.0.0.1:38530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6519,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:14:02.349763Z","caller":"traceutil/trace.go:171","msg":"trace[834162079] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"343.302867ms","start":"2026-02-09T03:14:02.006447Z","end":"2026-02-09T03:14:02.34975Z","steps":["trace[834162079] 'process raft request' (duration: 340.497333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:02.349969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:02.006437Z","time spent":"343.359647ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:02.547085Z","caller":"traceutil/trace.go:171","msg":"trace[692260605] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"189.638645ms","start":"2026-02-09T03:14:02.357419Z","end":"2026-02-09T03:14:02.547057Z","steps":["trace[692260605] 'process raft request' (duration: 148.199337ms)","trace[692260605] 'compare' (duration: 41.226687ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:02.565074Z","caller":"traceutil/trace.go:171","msg":"trace[146897136] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"207.401544ms","start":"2026-02-09T03:14:02.357654Z","end":"2026-02-09T03:14:02.565056Z","steps":["trace[146897136] 'process raft request' (duration: 207.312593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:02.766136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.114834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:14:02.766283Z","caller":"traceutil/trace.go:171","msg":"trace[1442586610] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"162.681638ms","start":"2026-02-09T03:14:02.603545Z","end":"2026-02-09T03:14:02.766227Z","steps":["trace[1442586610] 'process raft request' (duration: 58.381394ms)","trace[1442586610] 'compare' (duration: 104.010953ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:02.930576Z","caller":"traceutil/trace.go:171","msg":"trace[1426601515] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"105.792402ms","start":"2026-02-09T03:14:02.82476Z","end":"2026-02-09T03:14:02.930553Z","steps":["trace[1426601515] 'process raft request' (duration: 94.090213ms)","trace[1426601515] 'compare' (duration: 11.434038ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:03.191348Z","caller":"traceutil/trace.go:171","msg":"trace[1623520044] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:453; }","duration":"176.607893ms","start":"2026-02-09T03:14:03.014717Z","end":"2026-02-09T03:14:03.191324Z","steps":["trace[1623520044] 'read index received' (duration: 140.00105ms)","trace[1623520044] 'applied index is now lower than readState.Index' (duration: 36.605983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:03.191798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.063064ms","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-09T03:14:03.191966Z","caller":"traceutil/trace.go:171","msg":"trace[941174412] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:442; }","duration":"177.246955ms","start":"2026-02-09T03:14:03.014677Z","end":"2026-02-09T03:14:03.191924Z","steps":["trace[941174412] 'agreement among raft nodes before linearized reading' (duration: 176.983654ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.192052Z","caller":"traceutil/trace.go:171","msg":"trace[2055312315] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"253.537359ms","start":"2026-02-09T03:14:02.938489Z","end":"2026-02-09T03:14:03.192026Z","steps":["trace[2055312315] 'process raft request' (duration: 216.185113ms)","trace[2055312315] 'compare' (duration: 36.347531ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:03.192483Z","caller":"traceutil/trace.go:171","msg":"trace[1161467602] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"252.619905ms","start":"2026-02-09T03:14:02.939848Z","end":"2026-02-09T03:14:03.192468Z","steps":["trace[1161467602] 'process raft request' (duration: 251.367239ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.387883Z","caller":"traceutil/trace.go:171","msg":"trace[215659168] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"181.90363ms","start":"2026-02-09T03:14:03.205961Z","end":"2026-02-09T03:14:03.387864Z","steps":["trace[215659168] 'process raft request' (duration: 181.85116ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.388358Z","caller":"traceutil/trace.go:171","msg":"trace[2115862432] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"182.539322ms","start":"2026-02-09T03:14:03.205807Z","end":"2026-02-09T03:14:03.388346Z","steps":["trace[2115862432] 'process raft request' (duration: 133.138946ms)","trace[2115862432] 'compare' (duration: 48.765323ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:03.479785Z","caller":"traceutil/trace.go:171","msg":"trace[929505794] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"198.202541ms","start":"2026-02-09T03:14:03.281573Z","end":"2026-02-09T03:14:03.479776Z","steps":["trace[929505794] 'process raft request' (duration: 196.614492ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.478937Z","caller":"traceutil/trace.go:171","msg":"trace[1470439887] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"102.567382ms","start":"2026-02-09T03:14:03.37635Z","end":"2026-02-09T03:14:03.478918Z","steps":["trace[1470439887] 'read index received' (duration: 101.893409ms)","trace[1470439887] 'applied index is now lower than readState.Index' (duration: 673.113µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:03.480255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.873098ms","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-09T03:14:03.480375Z","caller":"traceutil/trace.go:171","msg":"trace[1162028442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:446; }","duration":"104.065059ms","start":"2026-02-09T03:14:03.376301Z","end":"2026-02-09T03:14:03.480366Z","steps":["trace[1162028442] 'agreement among raft nodes before linearized reading' (duration: 103.889778ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.609455Z","caller":"traceutil/trace.go:171","msg":"trace[884202983] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"207.702169ms","start":"2026-02-09T03:14:03.40173Z","end":"2026-02-09T03:14:03.609432Z","steps":["trace[884202983] 'process raft request' (duration: 207.610508ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.609626Z","caller":"traceutil/trace.go:171","msg":"trace[1414462105] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"210.749003ms","start":"2026-02-09T03:14:03.398846Z","end":"2026-02-09T03:14:03.609595Z","steps":["trace[1414462105] 'process raft request' (duration: 159.838099ms)","trace[1414462105] 'compare' (duration: 50.448612ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:03.609709Z","caller":"traceutil/trace.go:171","msg":"trace[280995414] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"208.05346ms","start":"2026-02-09T03:14:03.401645Z","end":"2026-02-09T03:14:03.609698Z","steps":["trace[280995414] 'process raft request' (duration: 207.625228ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.609875Z","caller":"traceutil/trace.go:171","msg":"trace[748626707] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"208.01373ms","start":"2026-02-09T03:14:03.401851Z","end":"2026-02-09T03:14:03.609865Z","steps":["trace[748626707] 'process raft request' (duration: 207.533487ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.610559Z","caller":"traceutil/trace.go:171","msg":"trace[213946227] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"208.666934ms","start":"2026-02-09T03:14:03.401882Z","end":"2026-02-09T03:14:03.610549Z","steps":["trace[213946227] 'process raft request' (duration: 207.92854ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.610602Z","caller":"traceutil/trace.go:171","msg":"trace[1739560384] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"206.574813ms","start":"2026-02-09T03:14:03.404014Z","end":"2026-02-09T03:14:03.610589Z","steps":["trace[1739560384] 'process raft request' (duration: 206.04521ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:03.610865Z","caller":"traceutil/trace.go:171","msg":"trace[1602231631] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"104.267531ms","start":"2026-02-09T03:14:03.506587Z","end":"2026-02-09T03:14:03.610854Z","steps":["trace[1602231631] 'process raft request' (duration: 103.531527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.201779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.803063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:14:04.202075Z","caller":"traceutil/trace.go:171","msg":"trace[482926837] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"577.616822ms","start":"2026-02-09T03:14:03.624432Z","end":"2026-02-09T03:14:04.202049Z","steps":["trace[482926837] 'process raft request' (duration: 309.460727ms)","trace[482926837] 'compare' (duration: 267.692182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:04.202196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:03.624414Z","time spent":"577.715572ms","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3600,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:04.202419Z","caller":"traceutil/trace.go:171","msg":"trace[49210934] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"572.863448ms","start":"2026-02-09T03:14:03.629545Z","end":"2026-02-09T03:14:04.202408Z","steps":["trace[49210934] 'process raft request' (duration: 572.413856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.202466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:03.629535Z","time spent":"572.906058ms","remote":"127.0.0.1:38510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-09T03:14:04.202569Z","caller":"traceutil/trace.go:171","msg":"trace[803060745] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"576.851477ms","start":"2026-02-09T03:14:03.625709Z","end":"2026-02-09T03:14:04.202561Z","steps":["trace[803060745] 'process raft request' (duration: 576.158485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.202603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:03.625702Z","time spent":"576.881958ms","remote":"127.0.0.1:38096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4272,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:04.202952Z","caller":"traceutil/trace.go:171","msg":"trace[1090072530] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:467; }","duration":"575.777481ms","start":"2026-02-09T03:14:03.627162Z","end":"2026-02-09T03:14:04.202939Z","steps":["trace[1090072530] 'read index received' (duration: 6.159551ms)","trace[1090072530] 'applied index is now lower than readState.Index' (duration: 569.61667ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:04.438223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.190552ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T03:14:04.438458Z","caller":"traceutil/trace.go:171","msg":"trace[1766764450] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"235.470359ms","start":"2026-02-09T03:14:04.202975Z","end":"2026-02-09T03:14:04.438445Z","steps":["trace[1766764450] 'read index received' (duration: 41.86µs)","trace[1766764450] 'applied index is now lower than readState.Index' (duration: 235.426939ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:04.438856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.685004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jvpf6\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-09T03:14:04.438997Z","caller":"traceutil/trace.go:171","msg":"trace[1657899891] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jvpf6; range_end:; response_count:1; response_revision:457; }","duration":"811.844594ms","start":"2026-02-09T03:14:03.62714Z","end":"2026-02-09T03:14:04.438985Z","steps":["trace[1657899891] 'agreement among raft nodes before linearized reading' (duration: 575.863882ms)","trace[1657899891] 'range keys from in-memory index tree' (duration: 235.780701ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:04.439135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:03.627134Z","time spent":"811.990496ms","remote":"127.0.0.1:38606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14944,"request content":"key:\"/registry/pods/kube-system/cilium-jvpf6\" "} {"level":"warn","ts":"2026-02-09T03:14:04.439408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.594238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:04.43957Z","caller":"traceutil/trace.go:171","msg":"trace[214066466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"331.787198ms","start":"2026-02-09T03:14:04.107775Z","end":"2026-02-09T03:14:04.439563Z","steps":["trace[214066466] 'agreement among raft nodes before linearized reading' (duration: 331.607808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.439653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:04.107744Z","time spent":"331.898709ms","remote":"127.0.0.1:38432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T03:14:04.439716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.080636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-09T03:14:04.439793Z","caller":"traceutil/trace.go:171","msg":"trace[251532054] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:457; }","duration":"810.181347ms","start":"2026-02-09T03:14:03.629596Z","end":"2026-02-09T03:14:04.439777Z","steps":["trace[251532054] 'agreement among raft nodes before linearized reading' (duration: 809.960485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.439833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:03.629592Z","time spent":"810.233437ms","remote":"127.0.0.1:38076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4336,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-09T03:14:04.440044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.129216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-09T03:14:04.440071Z","caller":"traceutil/trace.go:171","msg":"trace[608322723] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:457; }","duration":"554.195056ms","start":"2026-02-09T03:14:03.885869Z","end":"2026-02-09T03:14:04.440065Z","steps":["trace[608322723] 'agreement among raft nodes before linearized reading' (duration: 554.126426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.44009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:03.885846Z","time spent":"554.238836ms","remote":"127.0.0.1:38636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-09T03:14:04.440375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.306242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-02-09T03:14:04.440403Z","caller":"traceutil/trace.go:171","msg":"trace[1960233950] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:457; }","duration":"687.362132ms","start":"2026-02-09T03:14:03.753034Z","end":"2026-02-09T03:14:04.440396Z","steps":["trace[1960233950] 'agreement among raft nodes before linearized reading' (duration: 687.152232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.440423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:03.753019Z","time spent":"687.399782ms","remote":"127.0.0.1:38518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329959,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-09T03:14:04.467644Z","caller":"traceutil/trace.go:171","msg":"trace[621879066] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"257.72467ms","start":"2026-02-09T03:14:04.209906Z","end":"2026-02-09T03:14:04.467631Z","steps":["trace[621879066] 'process raft request' (duration: 257.617499ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:04.504409Z","caller":"traceutil/trace.go:171","msg":"trace[1372755224] transaction","detail":"{read_only:false; number_of_response:1; response_revision:458; }","duration":"292.999465ms","start":"2026-02-09T03:14:04.211384Z","end":"2026-02-09T03:14:04.504383Z","steps":["trace[1372755224] 'process raft request' (duration: 292.841025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.504763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.213857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-09T03:14:04.504792Z","caller":"traceutil/trace.go:171","msg":"trace[2037745706] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:460; }","duration":"120.361748ms","start":"2026-02-09T03:14:04.384423Z","end":"2026-02-09T03:14:04.504785Z","steps":["trace[2037745706] 'agreement among raft nodes before linearized reading' (duration: 120.283137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:04.504773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.642487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T03:14:04.504837Z","caller":"traceutil/trace.go:171","msg":"trace[66037912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:460; }","duration":"146.832349ms","start":"2026-02-09T03:14:04.357988Z","end":"2026-02-09T03:14:04.504821Z","steps":["trace[66037912] 'agreement among raft nodes before linearized reading' (duration: 146.729598ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:04.81841Z","caller":"traceutil/trace.go:171","msg":"trace[1944212036] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"195.975333ms","start":"2026-02-09T03:14:04.622415Z","end":"2026-02-09T03:14:04.81839Z","steps":["trace[1944212036] 'process raft request' (duration: 189.519621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:05.069953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.588982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:14:05.070049Z","caller":"traceutil/trace.go:171","msg":"trace[239611398] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"234.307573ms","start":"2026-02-09T03:14:04.835726Z","end":"2026-02-09T03:14:05.070034Z","steps":["trace[239611398] 'read index received' (duration: 116.50531ms)","trace[239611398] 'applied index is now lower than readState.Index' (duration: 117.801273ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:05.070488Z","caller":"traceutil/trace.go:171","msg":"trace[189356027] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"242.540723ms","start":"2026-02-09T03:14:04.827809Z","end":"2026-02-09T03:14:05.07035Z","steps":["trace[189356027] 'process raft request' (duration: 124.473489ms)","trace[189356027] 'compare' (duration: 117.445911ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:05.071047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.307227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" ","response":"range_response_count:1 size:4332"} {"level":"info","ts":"2026-02-09T03:14:05.071089Z","caller":"traceutil/trace.go:171","msg":"trace[653134928] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd; range_end:; response_count:1; response_revision:464; }","duration":"235.365378ms","start":"2026-02-09T03:14:04.835711Z","end":"2026-02-09T03:14:05.071076Z","steps":["trace[653134928] 'agreement among raft nodes before linearized reading' (duration: 235.229177ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:05.367106Z","caller":"traceutil/trace.go:171","msg":"trace[271243394] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"149.710908ms","start":"2026-02-09T03:14:05.21737Z","end":"2026-02-09T03:14:05.367081Z","steps":["trace[271243394] 'read index received' (duration: 59.161682ms)","trace[271243394] 'applied index is now lower than readState.Index' (duration: 90.548306ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:05.367341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.95037ms","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-09T03:14:05.367374Z","caller":"traceutil/trace.go:171","msg":"trace[529346790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"150.05193ms","start":"2026-02-09T03:14:05.217312Z","end":"2026-02-09T03:14:05.367364Z","steps":["trace[529346790] 'agreement among raft nodes before linearized reading' (duration: 149.879619ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:05.36794Z","caller":"traceutil/trace.go:171","msg":"trace[701973898] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"290.517344ms","start":"2026-02-09T03:14:05.077245Z","end":"2026-02-09T03:14:05.367762Z","steps":["trace[701973898] 'process raft request' (duration: 199.374174ms)","trace[701973898] 'compare' (duration: 90.338026ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:05.820822Z","caller":"traceutil/trace.go:171","msg":"trace[2049914213] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"178.249299ms","start":"2026-02-09T03:14:05.642556Z","end":"2026-02-09T03:14:05.820805Z","steps":["trace[2049914213] 'process raft request' (duration: 178.160889ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:06.577356Z","caller":"traceutil/trace.go:171","msg":"trace[1066144144] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"422.870375ms","start":"2026-02-09T03:14:06.154466Z","end":"2026-02-09T03:14:06.577337Z","steps":["trace[1066144144] 'process raft request' (duration: 422.750524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:06.577729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:06.154452Z","time spent":"423.214497ms","remote":"127.0.0.1:38592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:06.609311Z","caller":"traceutil/trace.go:171","msg":"trace[75334966] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"224.1071ms","start":"2026-02-09T03:14:06.385183Z","end":"2026-02-09T03:14:06.60929Z","steps":["trace[75334966] 'process raft request' (duration: 224.033329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:07.068284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.939112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:14:07.068348Z","caller":"traceutil/trace.go:171","msg":"trace[130567538] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"441.512415ms","start":"2026-02-09T03:14:06.626823Z","end":"2026-02-09T03:14:07.068335Z","steps":["trace[130567538] 'process raft request' (duration: 277.469742ms)","trace[130567538] 'compare' (duration: 163.694401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:07.068377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:06.626812Z","time spent":"441.553205ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:09.497968Z","caller":"traceutil/trace.go:171","msg":"trace[1906236679] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"104.020251ms","start":"2026-02-09T03:14:09.393926Z","end":"2026-02-09T03:14:09.497946Z","steps":["trace[1906236679] 'process raft request' (duration: 103.69846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:09.832644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.578877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T03:14:09.832749Z","caller":"traceutil/trace.go:171","msg":"trace[1063406430] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"152.323924ms","start":"2026-02-09T03:14:09.680399Z","end":"2026-02-09T03:14:09.832723Z","steps":["trace[1063406430] 'read index received' (duration: 5.561607ms)","trace[1063406430] 'applied index is now lower than readState.Index' (duration: 146.761167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:09.832874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.474075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:09.832942Z","caller":"traceutil/trace.go:171","msg":"trace[1178050517] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:479; }","duration":"152.571315ms","start":"2026-02-09T03:14:09.680357Z","end":"2026-02-09T03:14:09.832928Z","steps":["trace[1178050517] 'agreement among raft nodes before linearized reading' (duration: 152.428365ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:09.833335Z","caller":"traceutil/trace.go:171","msg":"trace[1874879021] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"330.901815ms","start":"2026-02-09T03:14:09.502414Z","end":"2026-02-09T03:14:09.833315Z","steps":["trace[1874879021] 'process raft request' (duration: 183.585155ms)","trace[1874879021] 'compare' (duration: 146.487166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:09.833432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:09.502395Z","time spent":"330.990517ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:10.055829Z","caller":"traceutil/trace.go:171","msg":"trace[48670915] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"143.44512ms","start":"2026-02-09T03:14:09.912359Z","end":"2026-02-09T03:14:10.055804Z","steps":["trace[48670915] 'process raft request' (duration: 143.36723ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:10.056009Z","caller":"traceutil/trace.go:171","msg":"trace[2011833333] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"143.871182ms","start":"2026-02-09T03:14:09.912124Z","end":"2026-02-09T03:14:10.055995Z","steps":["trace[2011833333] 'process raft request' (duration: 143.3437ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:10.056124Z","caller":"traceutil/trace.go:171","msg":"trace[160265198] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"143.859052ms","start":"2026-02-09T03:14:09.912249Z","end":"2026-02-09T03:14:10.056108Z","steps":["trace[160265198] 'process raft request' (duration: 143.416171ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:10.055831Z","caller":"traceutil/trace.go:171","msg":"trace[480300728] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"143.866052ms","start":"2026-02-09T03:14:09.911937Z","end":"2026-02-09T03:14:10.055803Z","steps":["trace[480300728] 'process raft request' (duration: 50.110211ms)","trace[480300728] 'compare' (duration: 93.251249ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:10.414286Z","caller":"traceutil/trace.go:171","msg":"trace[141476423] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"111.608526ms","start":"2026-02-09T03:14:10.302657Z","end":"2026-02-09T03:14:10.414265Z","steps":["trace[141476423] 'process raft request' (duration: 48.700774ms)","trace[141476423] 'compare' (duration: 62.816582ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:10.855623Z","caller":"traceutil/trace.go:171","msg":"trace[1929041544] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"177.604972ms","start":"2026-02-09T03:14:10.677991Z","end":"2026-02-09T03:14:10.855596Z","steps":["trace[1929041544] 'process raft request' (duration: 177.417541ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:10.855903Z","caller":"traceutil/trace.go:171","msg":"trace[1526281388] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"174.935849ms","start":"2026-02-09T03:14:10.68096Z","end":"2026-02-09T03:14:10.855895Z","steps":["trace[1526281388] 'process raft request' (duration: 174.596017ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:10.856182Z","caller":"traceutil/trace.go:171","msg":"trace[1939358643] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:513; }","duration":"135.104968ms","start":"2026-02-09T03:14:10.721069Z","end":"2026-02-09T03:14:10.856174Z","steps":["trace[1939358643] 'read index received' (duration: 43.083717ms)","trace[1939358643] 'applied index is now lower than readState.Index' (duration: 92.020611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:10.856383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.295199ms","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-09T03:14:10.856409Z","caller":"traceutil/trace.go:171","msg":"trace[1230507887] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:501; }","duration":"135.35884ms","start":"2026-02-09T03:14:10.721041Z","end":"2026-02-09T03:14:10.8564Z","steps":["trace[1230507887] 'agreement among raft nodes before linearized reading' (duration: 135.199339ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:10.856436Z","caller":"traceutil/trace.go:171","msg":"trace[259205243] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"175.06758ms","start":"2026-02-09T03:14:10.681341Z","end":"2026-02-09T03:14:10.856409Z","steps":["trace[259205243] 'process raft request' (duration: 174.527447ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:10.856526Z","caller":"traceutil/trace.go:171","msg":"trace[1066186280] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"160.60178ms","start":"2026-02-09T03:14:10.695915Z","end":"2026-02-09T03:14:10.856517Z","steps":["trace[1066186280] 'process raft request' (duration: 160.221828ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:11.351868Z","caller":"traceutil/trace.go:171","msg":"trace[1427630067] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"173.060237ms","start":"2026-02-09T03:14:11.178788Z","end":"2026-02-09T03:14:11.351849Z","steps":["trace[1427630067] 'process raft request' (duration: 172.942056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:12.80925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294031253s","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-09T03:14:12.809312Z","caller":"traceutil/trace.go:171","msg":"trace[213402002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"1.294134524s","start":"2026-02-09T03:14:11.515162Z","end":"2026-02-09T03:14:12.809296Z","steps":["trace[213402002] 'range keys from in-memory index tree' (duration: 1.293906893s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:12.809344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:11.515144Z","time spent":"1.294187504s","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T03:14:12.80957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.804556ms","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-09T03:14:12.809595Z","caller":"traceutil/trace.go:171","msg":"trace[1305001605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:504; }","duration":"967.853977ms","start":"2026-02-09T03:14:11.841732Z","end":"2026-02-09T03:14:12.809586Z","steps":["trace[1305001605] 'range keys from in-memory index tree' (duration: 967.636066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:12.80961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:11.841716Z","time spent":"967.889727ms","remote":"127.0.0.1:38700","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-09T03:14:12.809895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.152046ms","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-09T03:14:12.809916Z","caller":"traceutil/trace.go:171","msg":"trace[19406102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"745.200226ms","start":"2026-02-09T03:14:12.06471Z","end":"2026-02-09T03:14:12.80991Z","steps":["trace[19406102] 'range keys from in-memory index tree' (duration: 745.061345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:12.809931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:12.064695Z","time spent":"745.233027ms","remote":"127.0.0.1:38700","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-09T03:14:13.2367Z","caller":"traceutil/trace.go:171","msg":"trace[539628066] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"422.208299ms","start":"2026-02-09T03:14:12.814471Z","end":"2026-02-09T03:14:13.236679Z","steps":["trace[539628066] 'process raft request' (duration: 422.089498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:13.23696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:12.814452Z","time spent":"422.314209ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:13.267687Z","caller":"traceutil/trace.go:171","msg":"trace[1000227469] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"161.134231ms","start":"2026-02-09T03:14:13.106533Z","end":"2026-02-09T03:14:13.267667Z","steps":["trace[1000227469] 'read index received' (duration: 131.070859ms)","trace[1000227469] 'applied index is now lower than readState.Index' (duration: 30.062482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:13.267845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.254581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:13.267912Z","caller":"traceutil/trace.go:171","msg":"trace[1369118144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"161.400582ms","start":"2026-02-09T03:14:13.106498Z","end":"2026-02-09T03:14:13.267899Z","steps":["trace[1369118144] 'agreement among raft nodes before linearized reading' (duration: 161.245341ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:13.268267Z","caller":"traceutil/trace.go:171","msg":"trace[802039754] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"453.362206ms","start":"2026-02-09T03:14:12.814893Z","end":"2026-02-09T03:14:13.268255Z","steps":["trace[802039754] 'process raft request' (duration: 452.637982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:13.268346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:12.814886Z","time spent":"453.411656ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T03:14:13.268457Z","caller":"traceutil/trace.go:171","msg":"trace[1852583022] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"449.416036ms","start":"2026-02-09T03:14:12.819033Z","end":"2026-02-09T03:14:13.268449Z","steps":["trace[1852583022] 'process raft request' (duration: 448.599542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:13.268536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:12.819013Z","time spent":"449.475887ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:14:13.545557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.725847ms","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-09T03:14:13.545608Z","caller":"traceutil/trace.go:171","msg":"trace[728373697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"185.822117ms","start":"2026-02-09T03:14:13.359775Z","end":"2026-02-09T03:14:13.545597Z","steps":["trace[728373697] 'range keys from in-memory index tree' (duration: 185.614437ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:13.850725Z","caller":"traceutil/trace.go:171","msg":"trace[1189308961] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"302.400548ms","start":"2026-02-09T03:14:13.548309Z","end":"2026-02-09T03:14:13.850709Z","steps":["trace[1189308961] 'process raft request' (duration: 302.311108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:13.850829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:13.548293Z","time spent":"302.480378ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:14:14.112472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.921543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-09T03:14:15.486222Z","caller":"traceutil/trace.go:171","msg":"trace[1909309218] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"242.777185ms","start":"2026-02-09T03:14:15.243426Z","end":"2026-02-09T03:14:15.486203Z","steps":["trace[1909309218] 'process raft request' (duration: 242.575324ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:15.486465Z","caller":"traceutil/trace.go:171","msg":"trace[1312446258] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:526; }","duration":"213.783ms","start":"2026-02-09T03:14:15.272673Z","end":"2026-02-09T03:14:15.486456Z","steps":["trace[1312446258] 'read index received' (duration: 213.77903ms)","trace[1312446258] 'applied index is now lower than readState.Index' (duration: 3.16µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:15.486605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.085676ms","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-09T03:14:15.48663Z","caller":"traceutil/trace.go:171","msg":"trace[2123014734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"213.144807ms","start":"2026-02-09T03:14:15.273479Z","end":"2026-02-09T03:14:15.486624Z","steps":["trace[2123014734] 'agreement among raft nodes before linearized reading' (duration: 213.032646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:15.486823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.149522ms","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-09T03:14:15.486867Z","caller":"traceutil/trace.go:171","msg":"trace[1987337190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"214.238022ms","start":"2026-02-09T03:14:15.272623Z","end":"2026-02-09T03:14:15.486861Z","steps":["trace[1987337190] 'agreement among raft nodes before linearized reading' (duration: 214.154511ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:15.646734Z","caller":"traceutil/trace.go:171","msg":"trace[2141527602] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"153.875409ms","start":"2026-02-09T03:14:15.492843Z","end":"2026-02-09T03:14:15.646718Z","steps":["trace[2141527602] 'process raft request' (duration: 153.791919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:18.607696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232309259718330227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:14:19.108465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232309259718330227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T03:14:19.146347Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.202706064s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T03:14:19.14662Z","caller":"traceutil/trace.go:171","msg":"trace[313051405] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"1.0412872s","start":"2026-02-09T03:14:18.105315Z","end":"2026-02-09T03:14:19.146603Z","steps":["trace[313051405] 'read index received' (duration: 1.041114859s)","trace[313051405] 'applied index is now lower than readState.Index' (duration: 171.561µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:14:19.14678Z","caller":"traceutil/trace.go:171","msg":"trace[943797716] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.203170937s","start":"2026-02-09T03:14:17.943592Z","end":"2026-02-09T03:14:19.146763Z","steps":["trace[943797716] 'process raft request' (duration: 1.202883796s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:19.146848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:17.943581Z","time spent":"1.203217887s","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:14:19.146968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041649371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:19.146991Z","caller":"traceutil/trace.go:171","msg":"trace[782821418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"1.041694091s","start":"2026-02-09T03:14:18.10529Z","end":"2026-02-09T03:14:19.146984Z","steps":["trace[782821418] 'agreement among raft nodes before linearized reading' (duration: 1.041658421s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:19.14701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:18.105276Z","time spent":"1.041728802s","remote":"127.0.0.1:38432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T03:14:19.147146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001265164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-09T03:14:19.147164Z","caller":"traceutil/trace.go:171","msg":"trace[1542349368] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:517; }","duration":"1.001305914s","start":"2026-02-09T03:14:18.145853Z","end":"2026-02-09T03:14:19.147159Z","steps":["trace[1542349368] 'agreement among raft nodes before linearized reading' (duration: 1.001237044s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:19.147195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:18.145841Z","time spent":"1.001348094s","remote":"127.0.0.1:38464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"info","ts":"2026-02-09T03:14:23.787147Z","caller":"traceutil/trace.go:171","msg":"trace[2052989292] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"101.147315ms","start":"2026-02-09T03:14:23.685978Z","end":"2026-02-09T03:14:23.787126Z","steps":["trace[2052989292] 'read index received' (duration: 12.423515ms)","trace[2052989292] 'applied index is now lower than readState.Index' (duration: 88.72287ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:23.787291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.434637ms","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-09T03:14:23.787309Z","caller":"traceutil/trace.go:171","msg":"trace[751429210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"101.4862ms","start":"2026-02-09T03:14:23.685816Z","end":"2026-02-09T03:14:23.787303Z","steps":["trace[751429210] 'agreement among raft nodes before linearized reading' (duration: 101.375775ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:24.071823Z","caller":"traceutil/trace.go:171","msg":"trace[1170814720] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"209.059158ms","start":"2026-02-09T03:14:23.862741Z","end":"2026-02-09T03:14:24.0718Z","steps":["trace[1170814720] 'read index received' (duration: 208.88263ms)","trace[1170814720] 'applied index is now lower than readState.Index' (duration: 175.678µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:24.072024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.263577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jvpf6\" ","response":"range_response_count:1 size:16648"} {"level":"info","ts":"2026-02-09T03:14:24.072058Z","caller":"traceutil/trace.go:171","msg":"trace[2097791356] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jvpf6; range_end:; response_count:1; response_revision:533; }","duration":"209.34108ms","start":"2026-02-09T03:14:23.862709Z","end":"2026-02-09T03:14:24.07205Z","steps":["trace[2097791356] 'agreement among raft nodes before linearized reading' (duration: 209.180443ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:24.072319Z","caller":"traceutil/trace.go:171","msg":"trace[1521329372] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"281.995605ms","start":"2026-02-09T03:14:23.790314Z","end":"2026-02-09T03:14:24.07231Z","steps":["trace[1521329372] 'process raft request' (duration: 281.362557ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:24.206925Z","caller":"traceutil/trace.go:171","msg":"trace[2075172649] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"102.680196ms","start":"2026-02-09T03:14:24.104229Z","end":"2026-02-09T03:14:24.206909Z","steps":["trace[2075172649] 'read index received' (duration: 102.55021ms)","trace[2075172649] 'applied index is now lower than readState.Index' (duration: 129.376µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:24.207032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.78691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:24.207061Z","caller":"traceutil/trace.go:171","msg":"trace[399023893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"102.868724ms","start":"2026-02-09T03:14:24.104187Z","end":"2026-02-09T03:14:24.207056Z","steps":["trace[399023893] 'agreement among raft nodes before linearized reading' (duration: 102.77843ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:24.207292Z","caller":"traceutil/trace.go:171","msg":"trace[1249605240] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"125.153938ms","start":"2026-02-09T03:14:24.082131Z","end":"2026-02-09T03:14:24.207285Z","steps":["trace[1249605240] 'process raft request' (duration: 124.697568ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:25.31259Z","caller":"traceutil/trace.go:171","msg":"trace[1543188173] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"145.266329ms","start":"2026-02-09T03:14:25.1673Z","end":"2026-02-09T03:14:25.312567Z","steps":["trace[1543188173] 'process raft request' (duration: 145.102712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:25.846113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.827329ms","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-09T03:14:25.846137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.933958ms","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-09T03:14:25.846194Z","caller":"traceutil/trace.go:171","msg":"trace[1660933566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"168.030092ms","start":"2026-02-09T03:14:25.678153Z","end":"2026-02-09T03:14:25.846183Z","steps":["trace[1660933566] 'range keys from in-memory index tree' (duration: 167.815854ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:25.846186Z","caller":"traceutil/trace.go:171","msg":"trace[162247685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"300.962985ms","start":"2026-02-09T03:14:25.545206Z","end":"2026-02-09T03:14:25.846169Z","steps":["trace[162247685] 'range keys from in-memory index tree' (duration: 300.61368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:14:25.846233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:14:25.545172Z","time spent":"301.052499ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-09T03:14:26.002484Z","caller":"traceutil/trace.go:171","msg":"trace[696652211] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"152.388693ms","start":"2026-02-09T03:14:25.850072Z","end":"2026-02-09T03:14:26.002461Z","steps":["trace[696652211] 'process raft request' (duration: 151.084668ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:14:28.365338Z","caller":"traceutil/trace.go:171","msg":"trace[1399967283] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"171.74876ms","start":"2026-02-09T03:14:28.193573Z","end":"2026-02-09T03:14:28.365322Z","steps":["trace[1399967283] 'read index received' (duration: 170.982079ms)","trace[1399967283] 'applied index is now lower than readState.Index' (duration: 765.791µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:14:28.365419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.840053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T03:14:28.365442Z","caller":"traceutil/trace.go:171","msg":"trace[1947011665] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:566; }","duration":"171.896856ms","start":"2026-02-09T03:14:28.193537Z","end":"2026-02-09T03:14:28.365434Z","steps":["trace[1947011665] 'agreement among raft nodes before linearized reading' (duration: 171.845563ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:15:46.701767Z","caller":"traceutil/trace.go:171","msg":"trace[606327799] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"266.295687ms","start":"2026-02-09T03:15:46.435449Z","end":"2026-02-09T03:15:46.701745Z","steps":["trace[606327799] 'process raft request' (duration: 266.129734ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T03:15:46.75957Z","caller":"traceutil/trace.go:171","msg":"trace[107751988] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"306.553034ms","start":"2026-02-09T03:15:46.452997Z","end":"2026-02-09T03:15:46.75955Z","steps":["trace[107751988] 'read index received' (duration: 249.573673ms)","trace[107751988] 'applied index is now lower than readState.Index' (duration: 56.978281ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T03:15:46.760048Z","caller":"traceutil/trace.go:171","msg":"trace[1357164557] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"324.362326ms","start":"2026-02-09T03:15:46.435676Z","end":"2026-02-09T03:15:46.760039Z","steps":["trace[1357164557] 'process raft request' (duration: 296.669166ms)","trace[1357164557] 'compare' (duration: 27.063769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T03:15:46.760131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:15:46.435667Z","time spent":"324.408507ms","remote":"127.0.0.1:38700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T03:15:46.760318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.321048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-09T03:15:46.760339Z","caller":"traceutil/trace.go:171","msg":"trace[318314895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"307.371329ms","start":"2026-02-09T03:15:46.452962Z","end":"2026-02-09T03:15:46.760333Z","steps":["trace[318314895] 'agreement among raft nodes before linearized reading' (duration: 307.287187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:15:46.760739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:15:46.452946Z","time spent":"307.41917ms","remote":"127.0.0.1:38700","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-09T03:15:46.760901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.365471ms","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-09T03:15:46.760919Z","caller":"traceutil/trace.go:171","msg":"trace[1868223871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"160.418712ms","start":"2026-02-09T03:15:46.600494Z","end":"2026-02-09T03:15:46.760913Z","steps":["trace[1868223871] 'agreement among raft nodes before linearized reading' (duration: 160.363691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:15:47.364172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.211879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T03:15:47.364239Z","caller":"traceutil/trace.go:171","msg":"trace[889587152] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:949; }","duration":"332.344382ms","start":"2026-02-09T03:15:47.031877Z","end":"2026-02-09T03:15:47.364221Z","steps":["trace[889587152] 'count revisions from in-memory index tree' (duration: 332.120547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T03:15:47.364268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T03:15:47.031858Z","time spent":"332.402973ms","remote":"127.0.0.1:38132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "}