{"level":"warn","ts":"2026-04-13T17:06:00.445472Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:06:00.445597Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.37: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.19.213.37:2380","--initial-cluster=instance=https://199.19.213.37:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.37:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.37: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-04-13T17:06:00.445684Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:06:00.445701Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-04-13T17:06:00.445757Z","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-04-13T17:06:00.446617Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"]} {"level":"info","ts":"2026-04-13T17:06:00.446712Z","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":8,"max-cpu-available":8,"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.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.37: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-04-13T17:06:00.450858Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.898379ms"} {"level":"info","ts":"2026-04-13T17:06:00.462206Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7252794153f4f3cd","cluster-id":"2552855285ac01f"} {"level":"info","ts":"2026-04-13T17:06:00.462274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:06:00.462295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 0"} {"level":"info","ts":"2026-04-13T17:06:00.462306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7252794153f4f3cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:06:00.462317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 1"} {"level":"info","ts":"2026-04-13T17:06:00.462381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"warn","ts":"2026-04-13T17:06:00.476653Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:06:00.478472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:06:00.47992Z","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-04-13T17:06:00.48217Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7252794153f4f3cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:06:00.48329Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7252794153f4f3cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:06:00.482407Z","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-04-13T17:06:00.484123Z","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-04-13T17:06:00.484144Z","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-04-13T17:06:00.484415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"info","ts":"2026-04-13T17:06:00.48468Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","added-peer-id":"7252794153f4f3cd","added-peer-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-04-13T17:06:00.488807Z","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-04-13T17:06:00.489026Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-04-13T17:06:00.489052Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-04-13T17:06:00.489055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7252794153f4f3cd","initial-advertise-peer-urls":["https://199.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:06:00.48912Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:06:00.763377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:06:00.763443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:06:00.76349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgPreVoteResp from 7252794153f4f3cd at term 1"} {"level":"info","ts":"2026-04-13T17:06:00.763547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:06:00.763563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgVoteResp from 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-04-13T17:06:00.76359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became leader at term 2"} {"level":"info","ts":"2026-04-13T17:06:00.763611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7252794153f4f3cd elected leader 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-04-13T17:06:00.76479Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7252794153f4f3cd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.37:2379]}","request-path":"/0/members/7252794153f4f3cd/attributes","cluster-id":"2552855285ac01f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:06:00.764961Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:06:00.765392Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:06:00.765551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:06:00.766817Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:06:00.767387Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:06:00.767426Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:06:00.767481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.37:2379"} {"level":"info","ts":"2026-04-13T17:06:00.774859Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:06:00.774949Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:06:00.774979Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-13T17:06:29.547071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.234433ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-13T17:06:36.793263Z","caller":"traceutil/trace.go:171","msg":"trace[759785177] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"167.55155ms","start":"2026-04-13T17:06:36.625655Z","end":"2026-04-13T17:06:36.793207Z","steps":["trace[759785177] 'process raft request' (duration: 167.384505ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.409219Z","caller":"traceutil/trace.go:171","msg":"trace[1698787658] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"649.623594ms","start":"2026-04-13T17:06:36.759575Z","end":"2026-04-13T17:06:37.409199Z","steps":["trace[1698787658] 'process raft request' (duration: 649.378667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:37.409506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.759555Z","time spent":"649.718787ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:06:37.409802Z","caller":"traceutil/trace.go:171","msg":"trace[682864695] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"642.670733ms","start":"2026-04-13T17:06:36.767094Z","end":"2026-04-13T17:06:37.409765Z","steps":["trace[682864695] 'process raft request' (duration: 642.061695ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.409886Z","caller":"traceutil/trace.go:171","msg":"trace[451028464] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"628.236996ms","start":"2026-04-13T17:06:36.781634Z","end":"2026-04-13T17:06:37.409871Z","steps":["trace[451028464] 'process raft request' (duration: 627.794543ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.409874Z","caller":"traceutil/trace.go:171","msg":"trace[165480277] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"628.071831ms","start":"2026-04-13T17:06:36.781766Z","end":"2026-04-13T17:06:37.409837Z","steps":["trace[165480277] 'process raft request' (duration: 627.856424ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.409889Z","caller":"traceutil/trace.go:171","msg":"trace[1573193497] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"628.074271ms","start":"2026-04-13T17:06:36.781729Z","end":"2026-04-13T17:06:37.409803Z","steps":["trace[1573193497] 'process raft request' (duration: 627.837874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:37.409947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.767076Z","time spent":"642.803797ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:06:37.409997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.781761Z","time spent":"628.174744ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:06:37.410047Z","caller":"traceutil/trace.go:171","msg":"trace[2005440751] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"628.155853ms","start":"2026-04-13T17:06:36.781883Z","end":"2026-04-13T17:06:37.410039Z","steps":["trace[2005440751] 'process raft request' (duration: 627.930796ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.410044Z","caller":"traceutil/trace.go:171","msg":"trace[697832156] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"628.206565ms","start":"2026-04-13T17:06:36.781829Z","end":"2026-04-13T17:06:37.410036Z","steps":["trace[697832156] 'process raft request' (duration: 627.841224ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.409983Z","caller":"traceutil/trace.go:171","msg":"trace[16782446] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"650.217102ms","start":"2026-04-13T17:06:36.759714Z","end":"2026-04-13T17:06:37.409931Z","steps":["trace[16782446] 'process raft request' (duration: 649.366596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:37.410055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.781724Z","time spent":"628.255316ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:06:37.410093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.781822Z","time spent":"628.250636ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:06:37.410067Z","caller":"traceutil/trace.go:171","msg":"trace[1727293014] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"623.209173ms","start":"2026-04-13T17:06:36.786796Z","end":"2026-04-13T17:06:37.410006Z","steps":["trace[1727293014] 'process raft request' (duration: 623.10703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:37.410083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.781878Z","time spent":"628.186115ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:06:37.410004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.781612Z","time spent":"628.323138ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:06:37.410254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.759706Z","time spent":"650.421678ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:06:37.410244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.786756Z","time spent":"623.439179ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:06:37.422236Z","caller":"traceutil/trace.go:171","msg":"trace[885731967] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"616.715924ms","start":"2026-04-13T17:06:36.805502Z","end":"2026-04-13T17:06:37.422218Z","steps":["trace[885731967] 'process raft request' (duration: 616.455106ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.422255Z","caller":"traceutil/trace.go:171","msg":"trace[737760760] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:547; }","duration":"393.787692ms","start":"2026-04-13T17:06:37.028447Z","end":"2026-04-13T17:06:37.422234Z","steps":["trace[737760760] 'read index received' (duration: 380.594036ms)","trace[737760760] 'applied index is now lower than readState.Index' (duration: 13.192496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:37.422346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.805472Z","time spent":"616.821627ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:06:37.422522Z","caller":"traceutil/trace.go:171","msg":"trace[717999274] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"591.635863ms","start":"2026-04-13T17:06:36.830878Z","end":"2026-04-13T17:06:37.422514Z","steps":["trace[717999274] 'process raft request' (duration: 591.172919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:37.422567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.830769Z","time spent":"591.775717ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:06:37.422695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.237536ms","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-04-13T17:06:37.422738Z","caller":"traceutil/trace.go:171","msg":"trace[53271403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"394.330019ms","start":"2026-04-13T17:06:37.028398Z","end":"2026-04-13T17:06:37.422728Z","steps":["trace[53271403] 'agreement among raft nodes before linearized reading' (duration: 394.220136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:37.422761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:37.028378Z","time spent":"394.37764ms","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T17:06:37.422704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.539894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:06:37.422746Z","caller":"traceutil/trace.go:171","msg":"trace[497598864] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"490.062683ms","start":"2026-04-13T17:06:36.932673Z","end":"2026-04-13T17:06:37.422736Z","steps":["trace[497598864] 'process raft request' (duration: 489.478896ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:37.422869Z","caller":"traceutil/trace.go:171","msg":"trace[1131806971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"309.74206ms","start":"2026-04-13T17:06:37.113121Z","end":"2026-04-13T17:06:37.422863Z","steps":["trace[1131806971] 'agreement among raft nodes before linearized reading' (duration: 309.559515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:37.42289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:37.113101Z","time spent":"309.784261ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:06:37.4229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:36.932642Z","time spent":"490.237998ms","remote":"127.0.0.1:42638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:06:44.263192Z","caller":"traceutil/trace.go:171","msg":"trace[232297718] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"152.104126ms","start":"2026-04-13T17:06:44.111056Z","end":"2026-04-13T17:06:44.26316Z","steps":["trace[232297718] 'read index received' (duration: 151.192669ms)","trace[232297718] 'applied index is now lower than readState.Index' (duration: 910.397µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:44.263373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.294221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:06:44.263422Z","caller":"traceutil/trace.go:171","msg":"trace[389716599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"152.388384ms","start":"2026-04-13T17:06:44.11102Z","end":"2026-04-13T17:06:44.263408Z","steps":["trace[389716599] 'agreement among raft nodes before linearized reading' (duration: 152.26368ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:44.264025Z","caller":"traceutil/trace.go:171","msg":"trace[447832551] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"157.706132ms","start":"2026-04-13T17:06:44.106259Z","end":"2026-04-13T17:06:44.263965Z","steps":["trace[447832551] 'process raft request' (duration: 156.245731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:44.854246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567870928055341951,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T17:06:44.903847Z","caller":"traceutil/trace.go:171","msg":"trace[132312771] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"582.158064ms","start":"2026-04-13T17:06:44.321663Z","end":"2026-04-13T17:06:44.903821Z","steps":["trace[132312771] 'process raft request' (duration: 581.817205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:44.904063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:44.321637Z","time spent":"582.339228ms","remote":"127.0.0.1:42844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:06:44.904087Z","caller":"traceutil/trace.go:171","msg":"trace[961416338] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:667; }","duration":"550.428312ms","start":"2026-04-13T17:06:44.353628Z","end":"2026-04-13T17:06:44.904057Z","steps":["trace[961416338] 'read index received' (duration: 550.412671ms)","trace[961416338] 'applied index is now lower than readState.Index' (duration: 12.95µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:44.90481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.161273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hklfj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-13T17:06:44.904874Z","caller":"traceutil/trace.go:171","msg":"trace[723017015] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hklfj; range_end:; response_count:1; response_revision:640; }","duration":"551.258686ms","start":"2026-04-13T17:06:44.353601Z","end":"2026-04-13T17:06:44.904859Z","steps":["trace[723017015] 'agreement among raft nodes before linearized reading' (duration: 550.606037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:44.904918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:44.353586Z","time spent":"551.321557ms","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-hklfj\" "} {"level":"warn","ts":"2026-04-13T17:06:45.451423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567870928055341957,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:06:47.11269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000377777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T17:06:47.112776Z","caller":"traceutil/trace.go:171","msg":"trace[1772925660] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00048315s","start":"2026-04-13T17:06:45.112272Z","end":"2026-04-13T17:06:47.112755Z","steps":["trace[1772925660] 'agreement among raft nodes before linearized reading' (duration: 2.000374497s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:47.112824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:45.112178Z","time spent":"2.000636644s","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:06:47.513859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.373169118s","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-04-13T17:06:47.513998Z","caller":"traceutil/trace.go:171","msg":"trace[1873570964] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:641; }","duration":"2.373318632s","start":"2026-04-13T17:06:45.140658Z","end":"2026-04-13T17:06:47.513976Z","steps":["trace[1873570964] 'range keys from in-memory index tree' (duration: 2.373145188s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:47.516279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.035511904s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:06:47.516406Z","caller":"traceutil/trace.go:171","msg":"trace[252931744] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"2.565786096s","start":"2026-04-13T17:06:44.950562Z","end":"2026-04-13T17:06:47.516348Z","steps":["trace[252931744] 'read index received' (duration: 529.81368ms)","trace[252931744] 'applied index is now lower than readState.Index' (duration: 2.035970956s)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:06:47.516515Z","caller":"traceutil/trace.go:171","msg":"trace[1760707246] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.591308113s","start":"2026-04-13T17:06:44.925195Z","end":"2026-04-13T17:06:47.516503Z","steps":["trace[1760707246] 'process raft request' (duration: 555.275926ms)","trace[1760707246] 'compare' (duration: 2.03320014s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:47.516619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:44.925153Z","time spent":"2.591398096s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:06:47.516655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.566081594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-13T17:06:47.516773Z","caller":"traceutil/trace.go:171","msg":"trace[768378590] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:642; }","duration":"2.566202067s","start":"2026-04-13T17:06:44.950548Z","end":"2026-04-13T17:06:47.51675Z","steps":["trace[768378590] 'agreement among raft nodes before linearized reading' (duration: 2.566039843s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:47.516851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:44.950491Z","time spent":"2.566343811s","remote":"127.0.0.1:42794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-13T17:06:48.016591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567870928055341959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:06:48.517216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567870928055341959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:06:48.641412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.617030595s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T17:06:48.642108Z","caller":"traceutil/trace.go:171","msg":"trace[715043396] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"1.125645541s","start":"2026-04-13T17:06:47.516437Z","end":"2026-04-13T17:06:48.642082Z","steps":["trace[715043396] 'read index received' (duration: 1.125134627s)","trace[715043396] 'applied index is now lower than readState.Index' (duration: 509.484µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:06:48.642386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.785896593s","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-04-13T17:06:48.642429Z","caller":"traceutil/trace.go:171","msg":"trace[881693195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"2.785983265s","start":"2026-04-13T17:06:45.856434Z","end":"2026-04-13T17:06:48.642418Z","steps":["trace[881693195] 'agreement among raft nodes before linearized reading' (duration: 2.785784099s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.642471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:45.85641Z","time spent":"2.786053067s","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T17:06:48.642555Z","caller":"traceutil/trace.go:171","msg":"trace[2112685980] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.61829323s","start":"2026-04-13T17:06:46.024237Z","end":"2026-04-13T17:06:48.64253Z","steps":["trace[2112685980] 'process raft request' (duration: 2.617394826s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.642672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:46.024204Z","time spent":"2.618403763s","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:06:48.642701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.881832014s","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-04-13T17:06:48.642732Z","caller":"traceutil/trace.go:171","msg":"trace[92364451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"2.881889086s","start":"2026-04-13T17:06:45.760834Z","end":"2026-04-13T17:06:48.642723Z","steps":["trace[92364451] 'agreement among raft nodes before linearized reading' (duration: 2.881801574s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.642758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:45.760815Z","time spent":"2.881938398s","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:06:48.642893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.080561609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T17:06:48.642926Z","caller":"traceutil/trace.go:171","msg":"trace[1795997450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"3.080621561s","start":"2026-04-13T17:06:45.562296Z","end":"2026-04-13T17:06:48.642917Z","steps":["trace[1795997450] 'agreement among raft nodes before linearized reading' (duration: 3.080527329s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.642922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529682041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:06:48.642955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:45.56228Z","time spent":"3.080668682s","remote":"127.0.0.1:42880","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-04-13T17:06:48.642961Z","caller":"traceutil/trace.go:171","msg":"trace[1247425291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.529742493s","start":"2026-04-13T17:06:47.113206Z","end":"2026-04-13T17:06:48.642948Z","steps":["trace[1247425291] 'agreement among raft nodes before linearized reading' (duration: 1.529689112s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.642992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:47.113186Z","time spent":"1.529799794s","remote":"127.0.0.1:42608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:06:48.643118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120397687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hklfj\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-04-13T17:06:48.64315Z","caller":"traceutil/trace.go:171","msg":"trace[2575229] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hklfj; range_end:; response_count:1; response_revision:643; }","duration":"1.120458649s","start":"2026-04-13T17:06:47.522684Z","end":"2026-04-13T17:06:48.643143Z","steps":["trace[2575229] 'agreement among raft nodes before linearized reading' (duration: 1.120344446s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.643176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:47.522653Z","time spent":"1.12051751s","remote":"127.0.0.1:42784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17167,"request content":"key:\"/registry/pods/kube-system/cilium-hklfj\" "} {"level":"warn","ts":"2026-04-13T17:06:48.64237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691893812s","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-04-13T17:06:48.64329Z","caller":"traceutil/trace.go:171","msg":"trace[1986570488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"1.692874088s","start":"2026-04-13T17:06:46.950396Z","end":"2026-04-13T17:06:48.64327Z","steps":["trace[1986570488] 'agreement among raft nodes before linearized reading' (duration: 1.691795119s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.643319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116650254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:06:48.643308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.610769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:06:48.643366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:46.950372Z","time spent":"1.69296562s","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T17:06:48.643398Z","caller":"traceutil/trace.go:171","msg":"trace[41115350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"600.744462ms","start":"2026-04-13T17:06:48.04263Z","end":"2026-04-13T17:06:48.643374Z","steps":["trace[41115350] 'agreement among raft nodes before linearized reading' (duration: 600.600469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.643461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:48.042611Z","time spent":"600.836965ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:06:48.643351Z","caller":"traceutil/trace.go:171","msg":"trace[1871132911] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:643; }","duration":"1.116693515s","start":"2026-04-13T17:06:47.526645Z","end":"2026-04-13T17:06:48.643338Z","steps":["trace[1871132911] 'agreement among raft nodes before linearized reading' (duration: 1.116650704s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:06:48.643571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:06:47.526638Z","time spent":"1.116919341s","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-13T17:06:51.823974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.007393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:06:51.824083Z","caller":"traceutil/trace.go:171","msg":"trace[31082399] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:694; }","duration":"235.166567ms","start":"2026-04-13T17:06:51.588892Z","end":"2026-04-13T17:06:51.824059Z","steps":["trace[31082399] 'range keys from in-memory index tree' (duration: 234.853059ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:06:53.782339Z","caller":"traceutil/trace.go:171","msg":"trace[1747732552] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"190.486491ms","start":"2026-04-13T17:06:53.591822Z","end":"2026-04-13T17:06:53.782308Z","steps":["trace[1747732552] 'process raft request' (duration: 190.038749ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:05.431194Z","caller":"traceutil/trace.go:171","msg":"trace[86203747] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"562.860933ms","start":"2026-04-13T17:07:04.868309Z","end":"2026-04-13T17:07:05.43117Z","steps":["trace[86203747] 'process raft request' (duration: 562.727099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:05.431361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:04.868278Z","time spent":"562.988996ms","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:06.454643Z","caller":"traceutil/trace.go:171","msg":"trace[893240900] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"1.58535939s","start":"2026-04-13T17:07:04.869266Z","end":"2026-04-13T17:07:06.454626Z","steps":["trace[893240900] 'process raft request' (duration: 1.585256968s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:07:06.454737Z","caller":"traceutil/trace.go:171","msg":"trace[230943160] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:841; }","duration":"1.194350552s","start":"2026-04-13T17:07:05.260367Z","end":"2026-04-13T17:07:06.454718Z","steps":["trace[230943160] 'read index received' (duration: 170.691658ms)","trace[230943160] 'applied index is now lower than readState.Index' (duration: 1.023658214s)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:06.45489Z","caller":"traceutil/trace.go:171","msg":"trace[69345867] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"1.585412961s","start":"2026-04-13T17:07:04.869469Z","end":"2026-04-13T17:07:06.454882Z","steps":["trace[69345867] 'process raft request' (duration: 1.585124524s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:06.454894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:04.869252Z","time spent":"1.585506653s","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:07:06.455007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:04.869464Z","time spent":"1.585485634s","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:07:06.455169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194797113s","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-04-13T17:07:06.455142Z","caller":"traceutil/trace.go:171","msg":"trace[1525911381] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.586025635s","start":"2026-04-13T17:07:04.869084Z","end":"2026-04-13T17:07:06.45511Z","steps":["trace[1525911381] 'process raft request' (duration: 1.515055372s)","trace[1525911381] 'compare' (duration: 69.891668ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:07:06.455197Z","caller":"traceutil/trace.go:171","msg":"trace[1188564246] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"1.194882885s","start":"2026-04-13T17:07:05.260307Z","end":"2026-04-13T17:07:06.45519Z","steps":["trace[1188564246] 'agreement among raft nodes before linearized reading' (duration: 1.194786103s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:06.455218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:05.260285Z","time spent":"1.194928606s","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T17:07:06.455294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:04.869073Z","time spent":"1.586136599s","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:07:06.462971Z","caller":"traceutil/trace.go:171","msg":"trace[1627471878] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"970.935244ms","start":"2026-04-13T17:07:05.492019Z","end":"2026-04-13T17:07:06.462954Z","steps":["trace[1627471878] 'process raft request' (duration: 970.75161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:06.462983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.197958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:06.463029Z","caller":"traceutil/trace.go:171","msg":"trace[1913068741] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:813; }","duration":"588.274059ms","start":"2026-04-13T17:07:05.87474Z","end":"2026-04-13T17:07:06.463014Z","steps":["trace[1913068741] 'agreement among raft nodes before linearized reading' (duration: 588.150906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:06.463062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:05.874724Z","time spent":"588.328941ms","remote":"127.0.0.1:43092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:07:06.463102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.20539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:07:06.463152Z","caller":"traceutil/trace.go:171","msg":"trace[1444295644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"350.455096ms","start":"2026-04-13T17:07:06.112688Z","end":"2026-04-13T17:07:06.463143Z","steps":["trace[1444295644] 'agreement among raft nodes before linearized reading' (duration: 350.304222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:07:06.463221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:05.492Z","time spent":"971.009047ms","remote":"127.0.0.1:42844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:07:06.463514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:07:06.112672Z","time spent":"350.513757ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:08:55.286072Z","caller":"traceutil/trace.go:171","msg":"trace[1796826388] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"245.928267ms","start":"2026-04-13T17:08:55.040112Z","end":"2026-04-13T17:08:55.28604Z","steps":["trace[1796826388] 'read index received' (duration: 245.783525ms)","trace[1796826388] 'applied index is now lower than readState.Index' (duration: 143.192µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:08:55.286129Z","caller":"traceutil/trace.go:171","msg":"trace[1316130936] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"246.697117ms","start":"2026-04-13T17:08:55.039402Z","end":"2026-04-13T17:08:55.286099Z","steps":["trace[1316130936] 'process raft request' (duration: 246.428503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:08:55.286351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.19416ms","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-04-13T17:08:55.286414Z","caller":"traceutil/trace.go:171","msg":"trace[1900487985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1331; }","duration":"246.385803ms","start":"2026-04-13T17:08:55.040014Z","end":"2026-04-13T17:08:55.2864Z","steps":["trace[1900487985] 'agreement among raft nodes before linearized reading' (duration: 246.17759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:08:55.299983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.850824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:08:55.300037Z","caller":"traceutil/trace.go:171","msg":"trace[238917376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"186.976006ms","start":"2026-04-13T17:08:55.113045Z","end":"2026-04-13T17:08:55.300021Z","steps":["trace[238917376] 'agreement among raft nodes before linearized reading' (duration: 186.900575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:08:55.30012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.994657ms","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-04-13T17:08:55.300171Z","caller":"traceutil/trace.go:171","msg":"trace[1467964887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1332; }","duration":"256.068587ms","start":"2026-04-13T17:08:55.044088Z","end":"2026-04-13T17:08:55.300156Z","steps":["trace[1467964887] 'agreement among raft nodes before linearized reading' (duration: 255.897115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:08:55.300672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.19895ms","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-04-13T17:08:55.300748Z","caller":"traceutil/trace.go:171","msg":"trace[792139817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1332; }","duration":"259.304731ms","start":"2026-04-13T17:08:55.041427Z","end":"2026-04-13T17:08:55.300732Z","steps":["trace[792139817] 'agreement among raft nodes before linearized reading' (duration: 258.432589ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:09:03.698944Z","caller":"traceutil/trace.go:171","msg":"trace[1649659245] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1420; }","duration":"356.3774ms","start":"2026-04-13T17:09:03.342543Z","end":"2026-04-13T17:09:03.698921Z","steps":["trace[1649659245] 'read index received' (duration: 26.66146ms)","trace[1649659245] 'applied index is now lower than readState.Index' (duration: 329.71502ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:09:03.699014Z","caller":"traceutil/trace.go:171","msg":"trace[1324020973] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"370.097275ms","start":"2026-04-13T17:09:03.328892Z","end":"2026-04-13T17:09:03.698989Z","steps":["trace[1324020973] 'process raft request' (duration: 340.447744ms)","trace[1324020973] 'compare' (duration: 29.237636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:09:03.699142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.584012ms","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-04-13T17:09:03.69917Z","caller":"traceutil/trace.go:171","msg":"trace[2050026782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"356.642894ms","start":"2026-04-13T17:09:03.34252Z","end":"2026-04-13T17:09:03.699163Z","steps":["trace[2050026782] 'agreement among raft nodes before linearized reading' (duration: 356.517012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:03.699171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:03.328883Z","time spent":"370.210507ms","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:09:03.699262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.539762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T17:09:03.699311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.572733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T17:09:03.699337Z","caller":"traceutil/trace.go:171","msg":"trace[235334235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"356.632183ms","start":"2026-04-13T17:09:03.342698Z","end":"2026-04-13T17:09:03.69933Z","steps":["trace[235334235] 'agreement among raft nodes before linearized reading' (duration: 356.569202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:03.699355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:03.34267Z","time spent":"356.680094ms","remote":"127.0.0.1:42880","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-04-13T17:09:03.699345Z","caller":"traceutil/trace.go:171","msg":"trace[710312960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1364; }","duration":"356.628493ms","start":"2026-04-13T17:09:03.342698Z","end":"2026-04-13T17:09:03.699326Z","steps":["trace[710312960] 'agreement among raft nodes before linearized reading' (duration: 356.43975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:03.699389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:03.342685Z","time spent":"356.694554ms","remote":"127.0.0.1:42880","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-04-13T17:09:03.699193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:03.342505Z","time spent":"356.680324ms","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T17:09:03.69925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.637383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:09:03.699496Z","caller":"traceutil/trace.go:171","msg":"trace[863837364] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1364; }","duration":"353.907546ms","start":"2026-04-13T17:09:03.345578Z","end":"2026-04-13T17:09:03.699486Z","steps":["trace[863837364] 'agreement among raft nodes before linearized reading' (duration: 353.647813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:03.699529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:09:03.345565Z","time spent":"353.954747ms","remote":"127.0.0.1:42748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-04-13T17:09:03.824878Z","caller":"traceutil/trace.go:171","msg":"trace[1067714981] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"121.791887ms","start":"2026-04-13T17:09:03.703033Z","end":"2026-04-13T17:09:03.824825Z","steps":["trace[1067714981] 'process raft request' (duration: 121.734946ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:09:03.825005Z","caller":"traceutil/trace.go:171","msg":"trace[627960995] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"121.902689ms","start":"2026-04-13T17:09:03.703015Z","end":"2026-04-13T17:09:03.824918Z","steps":["trace[627960995] 'process raft request' (duration: 118.562665ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:09:03.825876Z","caller":"traceutil/trace.go:171","msg":"trace[21637724] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1422; }","duration":"121.794177ms","start":"2026-04-13T17:09:03.704066Z","end":"2026-04-13T17:09:03.82586Z","steps":["trace[21637724] 'read index received' (duration: 117.52875ms)","trace[21637724] 'applied index is now lower than readState.Index' (duration: 4.264467ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:09:03.825952Z","caller":"traceutil/trace.go:171","msg":"trace[1284666765] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"122.81231ms","start":"2026-04-13T17:09:03.703096Z","end":"2026-04-13T17:09:03.825909Z","steps":["trace[1284666765] 'process raft request' (duration: 122.684029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:09:03.826039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.952119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:09:03.82637Z","caller":"traceutil/trace.go:171","msg":"trace[1902715982] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1367; }","duration":"122.302103ms","start":"2026-04-13T17:09:03.704047Z","end":"2026-04-13T17:09:03.826349Z","steps":["trace[1902715982] 'agreement among raft nodes before linearized reading' (duration: 121.879278ms)"],"step_count":1}