{"level":"warn","ts":"2026-02-01T18:53:55.915933Z","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-01T18:53:55.916389Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-01T18:53:55.916798Z","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-01T18:53:55.916869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-02-01T18:53:55.916939Z","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-01T18:53:55.917839Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-02-01T18:53:55.918105Z","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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-01T18:53:55.923678Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.209571ms"} {"level":"info","ts":"2026-02-01T18:53:55.937838Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-02-01T18:53:55.938115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:53:55.938199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-02-01T18:53:55.938931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:53:55.939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-02-01T18:53:55.939089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-02-01T18:53:55.94878Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:53:55.953867Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:53:55.957756Z","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-01T18:53:55.959699Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:53:55.963794Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:53:55.964292Z","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-01T18:53:55.964352Z","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-01T18:53:55.964366Z","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-01T18:53:55.968263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-02-01T18:53:55.970302Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-02-01T18:53:55.971302Z","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-01T18:53:55.973442Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:53:55.973242Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-02-01T18:53:55.977023Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:53:55.977194Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-02-01T18:53:56.841657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:53:56.841701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:53:56.841727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-02-01T18:53:56.841739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:53:56.841745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-02-01T18:53:56.841755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-02-01T18:53:56.841763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-02-01T18:53:56.844263Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:53:56.846431Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:53:56.846499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:53:56.84688Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:53:56.846978Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:53:56.847026Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:53:56.847044Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:53:56.851771Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:53:56.893686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:53:56.893756Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:53:56.894026Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-02-01T18:54:02.093813Z","caller":"traceutil/trace.go:171","msg":"trace[1396240626] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:282; }","duration":"126.149646ms","start":"2026-02-01T18:54:01.96761Z","end":"2026-02-01T18:54:02.09376Z","steps":["trace[1396240626] 'read index received' (duration: 119.753451ms)","trace[1396240626] 'applied index is now lower than readState.Index' (duration: 6.393845ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:02.093852Z","caller":"traceutil/trace.go:171","msg":"trace[1191810477] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"145.129477ms","start":"2026-02-01T18:54:01.948696Z","end":"2026-02-01T18:54:02.093826Z","steps":["trace[1191810477] 'process raft request' (duration: 138.919476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:02.094038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.670915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:02.094063Z","caller":"traceutil/trace.go:171","msg":"trace[836894141] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:276; }","duration":"149.721866ms","start":"2026-02-01T18:54:01.944331Z","end":"2026-02-01T18:54:02.094053Z","steps":["trace[836894141] 'agreement among raft nodes before linearized reading' (duration: 149.627724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:02.094325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.131538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-01T18:54:02.094344Z","caller":"traceutil/trace.go:171","msg":"trace[1350771363] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:276; }","duration":"152.155888ms","start":"2026-02-01T18:54:01.94218Z","end":"2026-02-01T18:54:02.094335Z","steps":["trace[1350771363] 'agreement among raft nodes before linearized reading' (duration: 152.118847ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:08.130196Z","caller":"traceutil/trace.go:171","msg":"trace[730326930] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"112.178789ms","start":"2026-02-01T18:54:08.01798Z","end":"2026-02-01T18:54:08.130159Z","steps":["trace[730326930] 'process raft request' (duration: 112.027246ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:08.274505Z","caller":"traceutil/trace.go:171","msg":"trace[1098831888] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"129.464516ms","start":"2026-02-01T18:54:08.145014Z","end":"2026-02-01T18:54:08.274479Z","steps":["trace[1098831888] 'process raft request' (duration: 129.307313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:11.19782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.360866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-01T18:54:11.197878Z","caller":"traceutil/trace.go:171","msg":"trace[3378203] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:405; }","duration":"179.436808ms","start":"2026-02-01T18:54:11.01843Z","end":"2026-02-01T18:54:11.197867Z","steps":["trace[3378203] 'range keys from in-memory index tree' (duration: 179.296065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:11.197904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.106354ms","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-01T18:54:11.197981Z","caller":"traceutil/trace.go:171","msg":"trace[1462550770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"358.214828ms","start":"2026-02-01T18:54:10.839737Z","end":"2026-02-01T18:54:11.197952Z","steps":["trace[1462550770] 'range keys from in-memory index tree' (duration: 358.015402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:11.198023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:10.839722Z","time spent":"358.292059ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:11.198072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.68644ms","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-01T18:54:11.198096Z","caller":"traceutil/trace.go:171","msg":"trace[564181008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"303.734181ms","start":"2026-02-01T18:54:10.894355Z","end":"2026-02-01T18:54:11.198089Z","steps":["trace[564181008] 'range keys from in-memory index tree' (duration: 303.632439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:11.19811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:10.894343Z","time spent":"303.762192ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:11.198247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.069094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:11.19826Z","caller":"traceutil/trace.go:171","msg":"trace[1208199990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"375.106284ms","start":"2026-02-01T18:54:10.82315Z","end":"2026-02-01T18:54:11.198256Z","steps":["trace[1208199990] 'range keys from in-memory index tree' (duration: 374.995812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:11.198272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:10.823136Z","time spent":"375.133544ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:12.205032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.636143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:12.20521Z","caller":"traceutil/trace.go:171","msg":"trace[1964923047] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"380.766546ms","start":"2026-02-01T18:54:11.824429Z","end":"2026-02-01T18:54:12.205195Z","steps":["trace[1964923047] 'read index received' (duration: 27.28µs)","trace[1964923047] 'applied index is now lower than readState.Index' (duration: 380.738406ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:12.205287Z","caller":"traceutil/trace.go:171","msg":"trace[1191986407] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"997.006836ms","start":"2026-02-01T18:54:11.20827Z","end":"2026-02-01T18:54:12.205277Z","steps":["trace[1191986407] 'process raft request' (duration: 996.868713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:12.205339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:11.208107Z","time spent":"997.203111ms","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4049,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:12.205428Z","caller":"traceutil/trace.go:171","msg":"trace[875077925] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.00189861s","start":"2026-02-01T18:54:11.203514Z","end":"2026-02-01T18:54:12.205412Z","steps":["trace[875077925] 'process raft request' (duration: 257.337328ms)","trace[875077925] 'compare' (duration: 743.47866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:12.205507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.073812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:12.205513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:11.203486Z","time spent":"1.001969532s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:12.205537Z","caller":"traceutil/trace.go:171","msg":"trace[1182589347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"381.129904ms","start":"2026-02-01T18:54:11.824398Z","end":"2026-02-01T18:54:12.205528Z","steps":["trace[1182589347] 'agreement among raft nodes before linearized reading' (duration: 381.082513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:12.205562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:11.824382Z","time spent":"381.173084ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:12.205961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.385584ms","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-01T18:54:12.215195Z","caller":"traceutil/trace.go:171","msg":"trace[1703903606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"312.64312ms","start":"2026-02-01T18:54:11.902539Z","end":"2026-02-01T18:54:12.215182Z","steps":["trace[1703903606] 'agreement among raft nodes before linearized reading' (duration: 303.391655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:12.216501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:11.902524Z","time spent":"313.956629ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:54:12.477041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.915662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:12.477162Z","caller":"traceutil/trace.go:171","msg":"trace[385586714] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"250.483322ms","start":"2026-02-01T18:54:12.22666Z","end":"2026-02-01T18:54:12.477144Z","steps":["trace[385586714] 'process raft request' (duration: 250.447561ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:12.477373Z","caller":"traceutil/trace.go:171","msg":"trace[684325734] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"255.458938ms","start":"2026-02-01T18:54:12.221908Z","end":"2026-02-01T18:54:12.477367Z","steps":["trace[684325734] 'process raft request' (duration: 120.166229ms)","trace[684325734] 'compare' (duration: 134.843999ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:20.383515Z","caller":"traceutil/trace.go:171","msg":"trace[741482679] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"111.988905ms","start":"2026-02-01T18:54:20.27151Z","end":"2026-02-01T18:54:20.383499Z","steps":["trace[741482679] 'read index received' (duration: 111.860033ms)","trace[741482679] 'applied index is now lower than readState.Index' (duration: 128.362µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:20.383661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.133938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-01T18:54:20.383691Z","caller":"traceutil/trace.go:171","msg":"trace[1184072643] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:460; }","duration":"112.256411ms","start":"2026-02-01T18:54:20.271429Z","end":"2026-02-01T18:54:20.383685Z","steps":["trace[1184072643] 'agreement among raft nodes before linearized reading' (duration: 112.137039ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:20.383886Z","caller":"traceutil/trace.go:171","msg":"trace[1782664188] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"127.338891ms","start":"2026-02-01T18:54:20.25652Z","end":"2026-02-01T18:54:20.383859Z","steps":["trace[1782664188] 'process raft request' (duration: 126.887551ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:29.994656Z","caller":"traceutil/trace.go:171","msg":"trace[572049654] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"121.614529ms","start":"2026-02-01T18:54:29.873023Z","end":"2026-02-01T18:54:29.994637Z","steps":["trace[572049654] 'process raft request' (duration: 115.427708ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:30.680536Z","caller":"traceutil/trace.go:171","msg":"trace[1702709379] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"608.770111ms","start":"2026-02-01T18:54:30.071753Z","end":"2026-02-01T18:54:30.680524Z","steps":["trace[1702709379] 'process raft request' (duration: 608.697089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:30.680621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.071733Z","time spent":"608.846353ms","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:54:32.213207Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.208117683s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:54:32.213732Z","caller":"traceutil/trace.go:171","msg":"trace[1652245085] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:552; }","duration":"2.023005466s","start":"2026-02-01T18:54:30.190695Z","end":"2026-02-01T18:54:32.213701Z","steps":["trace[1652245085] 'read index received' (duration: 489.822298ms)","trace[1652245085] 'applied index is now lower than readState.Index' (duration: 1.533182578s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:32.213956Z","caller":"traceutil/trace.go:171","msg":"trace[288484051] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"2.12176023s","start":"2026-02-01T18:54:30.092188Z","end":"2026-02-01T18:54:32.213948Z","steps":["trace[288484051] 'process raft request' (duration: 2.121232249s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:32.214031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.092172Z","time spent":"2.121803721s","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4609,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:32.214237Z","caller":"traceutil/trace.go:171","msg":"trace[1439834530] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"2.115138561s","start":"2026-02-01T18:54:30.09909Z","end":"2026-02-01T18:54:32.214229Z","steps":["trace[1439834530] 'process raft request' (duration: 2.114460405s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:32.214274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.099082Z","time spent":"2.115171302s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:32.214355Z","caller":"traceutil/trace.go:171","msg":"trace[66015625] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.93923977s","start":"2026-02-01T18:54:30.275109Z","end":"2026-02-01T18:54:32.214348Z","steps":["trace[66015625] 'process raft request' (duration: 1.938518274s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:32.214381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.275092Z","time spent":"1.93927698s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:54:32.214455Z","caller":"traceutil/trace.go:171","msg":"trace[1929927564] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.743635781s","start":"2026-02-01T18:54:30.470813Z","end":"2026-02-01T18:54:32.214448Z","steps":["trace[1929927564] 'process raft request' (duration: 1.742843994s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:32.214479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.470799Z","time spent":"1.743669413s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:54:32.214551Z","caller":"traceutil/trace.go:171","msg":"trace[732403391] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.542022655s","start":"2026-02-01T18:54:30.672523Z","end":"2026-02-01T18:54:32.214546Z","steps":["trace[732403391] 'process raft request' (duration: 1.541154996s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:32.214576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.672482Z","time spent":"1.542081807s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:54:32.214662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.023965736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:54:32.214683Z","caller":"traceutil/trace.go:171","msg":"trace[1751882127] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:540; }","duration":"2.024018178s","start":"2026-02-01T18:54:30.190659Z","end":"2026-02-01T18:54:32.214677Z","steps":["trace[1751882127] 'agreement among raft nodes before linearized reading' (duration: 2.023979277s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:32.214702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.190642Z","time spent":"2.024056568s","remote":"127.0.0.1:50806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-02-01T18:54:32.823861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000055499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-01T18:54:32.823944Z","caller":"traceutil/trace.go:171","msg":"trace[1818322956] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000195692s","start":"2026-02-01T18:54:30.82373Z","end":"2026-02-01T18:54:32.823926Z","steps":["trace[1818322956] 'agreement among raft nodes before linearized reading' (duration: 2.00008317s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:32.823989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.823713Z","time spent":"2.000267693s","remote":"127.0.0.1:50776","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-02-01T18:54:33.130574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.590831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:33.131047Z","caller":"traceutil/trace.go:171","msg":"trace[1783458088] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:557; }","duration":"917.214144ms","start":"2026-02-01T18:54:32.213778Z","end":"2026-02-01T18:54:33.130992Z","steps":["trace[1783458088] 'read index received' (duration: 496.070171ms)","trace[1783458088] 'applied index is now lower than readState.Index' (duration: 421.142943ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:33.131289Z","caller":"traceutil/trace.go:171","msg":"trace[539286948] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"2.447133991s","start":"2026-02-01T18:54:30.68414Z","end":"2026-02-01T18:54:33.131274Z","steps":["trace[539286948] 'process raft request' (duration: 2.025761324s)","trace[539286948] 'compare' (duration: 420.337885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:33.131362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.684129Z","time spent":"2.447192162s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:33.131558Z","caller":"traceutil/trace.go:171","msg":"trace[1727225063] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"2.258010959s","start":"2026-02-01T18:54:30.873534Z","end":"2026-02-01T18:54:33.131544Z","steps":["trace[1727225063] 'process raft request' (duration: 2.257155541s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.131606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.873522Z","time spent":"2.258060561s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:54:33.131756Z","caller":"traceutil/trace.go:171","msg":"trace[1131075439] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"2.060344337s","start":"2026-02-01T18:54:31.071401Z","end":"2026-02-01T18:54:33.131746Z","steps":["trace[1131075439] 'process raft request' (duration: 2.059343106s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.131808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:31.071384Z","time spent":"2.060396618s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:54:33.132114Z","caller":"traceutil/trace.go:171","msg":"trace[1965584978] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.73654001s","start":"2026-02-01T18:54:31.395562Z","end":"2026-02-01T18:54:33.132102Z","steps":["trace[1965584978] 'process raft request' (duration: 1.735349085s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.132197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:31.395542Z","time spent":"1.736615581s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:54:33.132495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059089512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-01T18:54:33.132546Z","caller":"traceutil/trace.go:171","msg":"trace[1773761843] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:544; }","duration":"1.059155033s","start":"2026-02-01T18:54:32.073378Z","end":"2026-02-01T18:54:33.132533Z","steps":["trace[1773761843] 'agreement among raft nodes before linearized reading' (duration: 1.05900154s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.132577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.073366Z","time spent":"1.059203574s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:54:33.132793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261714239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-01T18:54:33.132823Z","caller":"traceutil/trace.go:171","msg":"trace[1832609523] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:544; }","duration":"1.261811161s","start":"2026-02-01T18:54:31.871003Z","end":"2026-02-01T18:54:33.132814Z","steps":["trace[1832609523] 'agreement among raft nodes before linearized reading' (duration: 1.261713809s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.132862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:31.870883Z","time spent":"1.261972424s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:54:33.133296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462731523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-01T18:54:33.133352Z","caller":"traceutil/trace.go:171","msg":"trace[1147030215] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:544; }","duration":"1.462801334s","start":"2026-02-01T18:54:31.670522Z","end":"2026-02-01T18:54:33.133323Z","steps":["trace[1147030215] 'agreement among raft nodes before linearized reading' (duration: 1.462682212s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.133383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:31.670504Z","time spent":"1.462870316s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:54:33.133457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.663292247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:33.133481Z","caller":"traceutil/trace.go:171","msg":"trace[1632406454] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:544; }","duration":"1.663331347s","start":"2026-02-01T18:54:31.470141Z","end":"2026-02-01T18:54:33.133472Z","steps":["trace[1632406454] 'agreement among raft nodes before linearized reading' (duration: 1.663299377s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.133505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:31.470131Z","time spent":"1.663368358s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:54:33.133638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665957753s","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-01T18:54:33.133662Z","caller":"traceutil/trace.go:171","msg":"trace[2025015649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.665981393s","start":"2026-02-01T18:54:31.467674Z","end":"2026-02-01T18:54:33.133655Z","steps":["trace[2025015649] 'agreement among raft nodes before linearized reading' (duration: 1.665878971s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.133686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:31.467647Z","time spent":"1.666032865s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:33.133872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666401132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-01T18:54:33.133899Z","caller":"traceutil/trace.go:171","msg":"trace[437165428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:544; }","duration":"1.666462584s","start":"2026-02-01T18:54:31.467428Z","end":"2026-02-01T18:54:33.133891Z","steps":["trace[437165428] 'agreement among raft nodes before linearized reading' (duration: 1.666388102s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.133925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:31.46741Z","time spent":"1.666509565s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:33.134079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.325591163s","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-01T18:54:33.134107Z","caller":"traceutil/trace.go:171","msg":"trace[1914960560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"2.325651114s","start":"2026-02-01T18:54:30.808447Z","end":"2026-02-01T18:54:33.134098Z","steps":["trace[1914960560] 'agreement among raft nodes before linearized reading' (duration: 2.325579103s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.134131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.808429Z","time spent":"2.325696685s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:54:33.134354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.582919411s","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-01T18:54:33.134383Z","caller":"traceutil/trace.go:171","msg":"trace[1811577423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"2.582950231s","start":"2026-02-01T18:54:30.551425Z","end":"2026-02-01T18:54:33.134375Z","steps":["trace[1811577423] 'agreement among raft nodes before linearized reading' (duration: 2.582790568s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.13441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.551395Z","time spent":"2.583008723s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:54:33.134708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.682642556s","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-01T18:54:33.134741Z","caller":"traceutil/trace.go:171","msg":"trace[1114380579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"2.682693678s","start":"2026-02-01T18:54:30.452039Z","end":"2026-02-01T18:54:33.134732Z","steps":["trace[1114380579] 'agreement among raft nodes before linearized reading' (duration: 2.682465163s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.134769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:30.452025Z","time spent":"2.682737618s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:33.631406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624274605042062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:54:33.797239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.763453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:33.797913Z","caller":"traceutil/trace.go:171","msg":"trace[1550505356] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:561; }","duration":"666.812343ms","start":"2026-02-01T18:54:33.131084Z","end":"2026-02-01T18:54:33.797897Z","steps":["trace[1550505356] 'read index received' (duration: 519.396455ms)","trace[1550505356] 'applied index is now lower than readState.Index' (duration: 147.414988ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:33.798543Z","caller":"traceutil/trace.go:171","msg":"trace[1862877758] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"622.332969ms","start":"2026-02-01T18:54:33.176178Z","end":"2026-02-01T18:54:33.798511Z","steps":["trace[1862877758] 'process raft request' (duration: 621.550713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.798679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.17617Z","time spent":"622.436491ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:33.798914Z","caller":"traceutil/trace.go:171","msg":"trace[1914160767] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"610.203293ms","start":"2026-02-01T18:54:33.188698Z","end":"2026-02-01T18:54:33.798902Z","steps":["trace[1914160767] 'process raft request' (duration: 609.055079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.798976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.188678Z","time spent":"610.270354ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:33.799085Z","caller":"traceutil/trace.go:171","msg":"trace[721977901] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.574131466s","start":"2026-02-01T18:54:32.224936Z","end":"2026-02-01T18:54:33.799068Z","steps":["trace[721977901] 'process raft request' (duration: 1.425459943s)","trace[721977901] 'compare' (duration: 145.445005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:33.799169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.22492Z","time spent":"1.574217838s","remote":"127.0.0.1:51282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:33.799423Z","caller":"traceutil/trace.go:171","msg":"trace[813901154] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"606.462202ms","start":"2026-02-01T18:54:33.192951Z","end":"2026-02-01T18:54:33.799413Z","steps":["trace[813901154] 'process raft request' (duration: 604.826768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.799474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.192926Z","time spent":"606.522243ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:33.799688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.225114Z","time spent":"1.574571605s","remote":"127.0.0.1:50806","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-01T18:54:33.799955Z","caller":"traceutil/trace.go:171","msg":"trace[1215871604] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"583.907004ms","start":"2026-02-01T18:54:33.215937Z","end":"2026-02-01T18:54:33.799844Z","steps":["trace[1215871604] 'process raft request' (duration: 581.894521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.799997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.21592Z","time spent":"584.053318ms","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:54:33.800705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.505255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:3368"} {"level":"info","ts":"2026-02-01T18:54:33.800735Z","caller":"traceutil/trace.go:171","msg":"trace[1092126327] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"930.557837ms","start":"2026-02-01T18:54:32.870163Z","end":"2026-02-01T18:54:33.800721Z","steps":["trace[1092126327] 'agreement among raft nodes before linearized reading' (duration: 930.319032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.800757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.870118Z","time spent":"930.632678ms","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":3392,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-01T18:54:33.80107Z","caller":"traceutil/trace.go:171","msg":"trace[437409186] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.200564466s","start":"2026-02-01T18:54:32.600489Z","end":"2026-02-01T18:54:33.801054Z","steps":["trace[437409186] 'process raft request' (duration: 1.196962559s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.801461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.600469Z","time spent":"1.200627086s","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4364,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:33.80316Z","caller":"traceutil/trace.go:171","msg":"trace[1489277227] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"978.255619ms","start":"2026-02-01T18:54:32.824887Z","end":"2026-02-01T18:54:33.803142Z","steps":["trace[1489277227] 'process raft request' (duration: 972.63087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.803209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.824867Z","time spent":"978.3158ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:33.803905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069174677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24235"} {"level":"info","ts":"2026-02-01T18:54:33.804096Z","caller":"traceutil/trace.go:171","msg":"trace[965076966] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"1.069221679s","start":"2026-02-01T18:54:32.734708Z","end":"2026-02-01T18:54:33.80393Z","steps":["trace[965076966] 'agreement among raft nodes before linearized reading' (duration: 1.066776617s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.804128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.734703Z","time spent":"1.069415362s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-02-01T18:54:33.805082Z","caller":"traceutil/trace.go:171","msg":"trace[1860230591] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"643.830685ms","start":"2026-02-01T18:54:33.161233Z","end":"2026-02-01T18:54:33.805064Z","steps":["trace[1860230591] 'process raft request' (duration: 636.317556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.805143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.161207Z","time spent":"643.902876ms","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:33.805823Z","caller":"traceutil/trace.go:171","msg":"trace[258730760] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"638.188976ms","start":"2026-02-01T18:54:33.167613Z","end":"2026-02-01T18:54:33.805802Z","steps":["trace[258730760] 'process raft request' (duration: 629.978511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.805999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.167594Z","time spent":"638.274968ms","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:33.806036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071422725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:8285"} {"level":"info","ts":"2026-02-01T18:54:33.806107Z","caller":"traceutil/trace.go:171","msg":"trace[803680663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"1.071512827s","start":"2026-02-01T18:54:32.734576Z","end":"2026-02-01T18:54:33.806089Z","steps":["trace[803680663] 'agreement among raft nodes before linearized reading' (duration: 1.071309542s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.806134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.734561Z","time spent":"1.071565888s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":8309,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:54:33.806549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528789645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-01T18:54:33.806759Z","caller":"traceutil/trace.go:171","msg":"trace[1613562808] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"1.528976939s","start":"2026-02-01T18:54:32.277667Z","end":"2026-02-01T18:54:33.806643Z","steps":["trace[1613562808] 'agreement among raft nodes before linearized reading' (duration: 1.528672783s)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:33.806768Z","caller":"traceutil/trace.go:171","msg":"trace[39345401] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"631.151266ms","start":"2026-02-01T18:54:33.175207Z","end":"2026-02-01T18:54:33.806358Z","steps":["trace[39345401] 'process raft request' (duration: 622.463612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.806786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.277648Z","time spent":"1.529130992s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-01T18:54:33.806826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.175191Z","time spent":"631.605435ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:33.807089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581910841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-lfbvz\" ","response":"range_response_count:1 size:4624"} {"level":"info","ts":"2026-02-01T18:54:33.807264Z","caller":"traceutil/trace.go:171","msg":"trace[444921594] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-lfbvz; range_end:; response_count:1; response_revision:555; }","duration":"1.582094395s","start":"2026-02-01T18:54:32.225161Z","end":"2026-02-01T18:54:33.807256Z","steps":["trace[444921594] 'agreement among raft nodes before linearized reading' (duration: 1.581842019s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.807288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.225157Z","time spent":"1.582123305s","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4648,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-lfbvz\" "} {"level":"info","ts":"2026-02-01T18:54:33.808207Z","caller":"traceutil/trace.go:171","msg":"trace[407524690] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"632.910583ms","start":"2026-02-01T18:54:33.17527Z","end":"2026-02-01T18:54:33.80818Z","steps":["trace[407524690] 'process raft request' (duration: 622.432792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.808246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:33.17526Z","time spent":"632.964275ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:33.801471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.711436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:33.808412Z","caller":"traceutil/trace.go:171","msg":"trace[1151965318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"983.696085ms","start":"2026-02-01T18:54:32.824709Z","end":"2026-02-01T18:54:33.808405Z","steps":["trace[1151965318] 'agreement among raft nodes before linearized reading' (duration: 976.647785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.808434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:32.824685Z","time spent":"983.740975ms","remote":"127.0.0.1:50784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:33.822846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.996015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:9358"} {"level":"info","ts":"2026-02-01T18:54:33.822896Z","caller":"traceutil/trace.go:171","msg":"trace[1034769217] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"137.091777ms","start":"2026-02-01T18:54:33.685793Z","end":"2026-02-01T18:54:33.822885Z","steps":["trace[1034769217] 'agreement among raft nodes before linearized reading' (duration: 136.957865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.82311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.393829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:187261"} {"level":"info","ts":"2026-02-01T18:54:33.823129Z","caller":"traceutil/trace.go:171","msg":"trace[1191814079] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"125.43255ms","start":"2026-02-01T18:54:33.697692Z","end":"2026-02-01T18:54:33.823125Z","steps":["trace[1191814079] 'agreement among raft nodes before linearized reading' (duration: 125.342389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:33.824915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.581479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:4249"} {"level":"info","ts":"2026-02-01T18:54:33.824938Z","caller":"traceutil/trace.go:171","msg":"trace[581566019] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"138.621949ms","start":"2026-02-01T18:54:33.68631Z","end":"2026-02-01T18:54:33.824932Z","steps":["trace[581566019] 'agreement among raft nodes before linearized reading' (duration: 138.567618ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.030287Z","caller":"traceutil/trace.go:171","msg":"trace[2145237282] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"208.103415ms","start":"2026-02-01T18:54:35.822167Z","end":"2026-02-01T18:54:36.03027Z","steps":["trace[2145237282] 'process raft request' (duration: 207.977002ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.030563Z","caller":"traceutil/trace.go:171","msg":"trace[1524063332] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:591; }","duration":"199.907511ms","start":"2026-02-01T18:54:35.830614Z","end":"2026-02-01T18:54:36.030521Z","steps":["trace[1524063332] 'read index received' (duration: 199.898651ms)","trace[1524063332] 'applied index is now lower than readState.Index' (duration: 7.16µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:36.030737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.092895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.030796Z","caller":"traceutil/trace.go:171","msg":"trace[153890623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"200.175806ms","start":"2026-02-01T18:54:35.830609Z","end":"2026-02-01T18:54:36.030785Z","steps":["trace[153890623] 'agreement among raft nodes before linearized reading' (duration: 200.058564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.428976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.392886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:36.429076Z","caller":"traceutil/trace.go:171","msg":"trace[824923674] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"398.43567ms","start":"2026-02-01T18:54:36.030624Z","end":"2026-02-01T18:54:36.429059Z","steps":["trace[824923674] 'read index received' (duration: 217.81877ms)","trace[824923674] 'applied index is now lower than readState.Index' (duration: 180.61559ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:36.42931Z","caller":"traceutil/trace.go:171","msg":"trace[629110264] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"594.369136ms","start":"2026-02-01T18:54:35.834925Z","end":"2026-02-01T18:54:36.429294Z","steps":["trace[629110264] 'process raft request' (duration: 413.574882ms)","trace[629110264] 'compare' (duration: 180.268333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:36.429373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.834915Z","time spent":"594.423797ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:36.429579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.50293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.429653Z","caller":"traceutil/trace.go:171","msg":"trace[1664239617] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:573; }","duration":"446.54362ms","start":"2026-02-01T18:54:35.983056Z","end":"2026-02-01T18:54:36.4296Z","steps":["trace[1664239617] 'agreement among raft nodes before linearized reading' (duration: 446.448998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.429688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.983039Z","time spent":"446.639552ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:54:36.429861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.797086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.429888Z","caller":"traceutil/trace.go:171","msg":"trace[692036888] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:573; }","duration":"446.826816ms","start":"2026-02-01T18:54:35.983053Z","end":"2026-02-01T18:54:36.42988Z","steps":["trace[692036888] 'agreement among raft nodes before linearized reading' (duration: 446.783335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.429921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.983014Z","time spent":"446.898048ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:54:36.430064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.02428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.430091Z","caller":"traceutil/trace.go:171","msg":"trace[1136225510] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:573; }","duration":"447.077241ms","start":"2026-02-01T18:54:35.983006Z","end":"2026-02-01T18:54:36.430083Z","steps":["trace[1136225510] 'agreement among raft nodes before linearized reading' (duration: 447.034421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.430117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.982992Z","time spent":"447.117512ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-01T18:54:36.430464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.098992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:54:36.430496Z","caller":"traceutil/trace.go:171","msg":"trace[983146280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:573; }","duration":"521.146373ms","start":"2026-02-01T18:54:35.909341Z","end":"2026-02-01T18:54:36.430487Z","steps":["trace[983146280] 'agreement among raft nodes before linearized reading' (duration: 520.968719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.430524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.909337Z","time spent":"521.179153ms","remote":"127.0.0.1:51098","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-01T18:54:36.430653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.056869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.430737Z","caller":"traceutil/trace.go:171","msg":"trace[548409002] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:573; }","duration":"527.146731ms","start":"2026-02-01T18:54:35.903581Z","end":"2026-02-01T18:54:36.430728Z","steps":["trace[548409002] 'agreement among raft nodes before linearized reading' (duration: 527.048519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.430801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.903577Z","time spent":"527.211102ms","remote":"127.0.0.1:54618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:54:36.43095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.367205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.430988Z","caller":"traceutil/trace.go:171","msg":"trace[582247970] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:573; }","duration":"527.412487ms","start":"2026-02-01T18:54:35.903568Z","end":"2026-02-01T18:54:36.43098Z","steps":["trace[582247970] 'agreement among raft nodes before linearized reading' (duration: 527.360076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.431017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.903566Z","time spent":"527.443808ms","remote":"127.0.0.1:54618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:54:36.431134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.5688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.431179Z","caller":"traceutil/trace.go:171","msg":"trace[1755488829] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:573; }","duration":"527.621301ms","start":"2026-02-01T18:54:35.903549Z","end":"2026-02-01T18:54:36.43117Z","steps":["trace[1755488829] 'agreement among raft nodes before linearized reading' (duration: 527.56144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.431205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.903546Z","time spent":"527.652362ms","remote":"127.0.0.1:54618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-02-01T18:54:36.431359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.924557ms","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-01T18:54:36.431383Z","caller":"traceutil/trace.go:171","msg":"trace[1016253228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"527.968388ms","start":"2026-02-01T18:54:35.903407Z","end":"2026-02-01T18:54:36.431376Z","steps":["trace[1016253228] 'agreement among raft nodes before linearized reading' (duration: 527.894047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.43141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.903395Z","time spent":"528.009359ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:54:36.43156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.775764ms","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-01T18:54:36.431584Z","caller":"traceutil/trace.go:171","msg":"trace[2017560915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"593.809115ms","start":"2026-02-01T18:54:35.837767Z","end":"2026-02-01T18:54:36.431577Z","steps":["trace[2017560915] 'agreement among raft nodes before linearized reading' (duration: 593.735173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.431607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.837762Z","time spent":"593.839605ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:36.431751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.878649ms","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-01T18:54:36.431775Z","caller":"traceutil/trace.go:171","msg":"trace[1675940598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"596.91429ms","start":"2026-02-01T18:54:35.834854Z","end":"2026-02-01T18:54:36.431768Z","steps":["trace[1675940598] 'agreement among raft nodes before linearized reading' (duration: 596.844638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.431799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.834846Z","time spent":"596.947421ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:54:36.431908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.107078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.431932Z","caller":"traceutil/trace.go:171","msg":"trace[509525775] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:573; }","duration":"601.136449ms","start":"2026-02-01T18:54:35.830788Z","end":"2026-02-01T18:54:36.431924Z","steps":["trace[509525775] 'agreement among raft nodes before linearized reading' (duration: 601.099588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.431989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.830784Z","time spent":"601.19617ms","remote":"127.0.0.1:54606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:54:36.432123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.333973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.43215Z","caller":"traceutil/trace.go:171","msg":"trace[361174428] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:573; }","duration":"601.367015ms","start":"2026-02-01T18:54:35.830775Z","end":"2026-02-01T18:54:36.432142Z","steps":["trace[361174428] 'agreement among raft nodes before linearized reading' (duration: 601.327445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.432174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.830773Z","time spent":"601.395526ms","remote":"127.0.0.1:54606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:54:36.432335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.562839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.432395Z","caller":"traceutil/trace.go:171","msg":"trace[2053987373] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:573; }","duration":"601.63092ms","start":"2026-02-01T18:54:35.830753Z","end":"2026-02-01T18:54:36.432384Z","steps":["trace[2053987373] 'agreement among raft nodes before linearized reading' (duration: 601.552959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.432425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:35.83075Z","time spent":"601.666801ms","remote":"127.0.0.1:54606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:54:36.432679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.693852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.432709Z","caller":"traceutil/trace.go:171","msg":"trace[52051717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"396.750274ms","start":"2026-02-01T18:54:36.03595Z","end":"2026-02-01T18:54:36.4327Z","steps":["trace[52051717] 'agreement among raft nodes before linearized reading' (duration: 396.645632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.432763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.035938Z","time spent":"396.818815ms","remote":"127.0.0.1:50784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:36.433793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.188231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.433844Z","caller":"traceutil/trace.go:171","msg":"trace[698071776] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:573; }","duration":"320.267464ms","start":"2026-02-01T18:54:36.113563Z","end":"2026-02-01T18:54:36.433831Z","steps":["trace[698071776] 'agreement among raft nodes before linearized reading' (duration: 320.156501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.433873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.11356Z","time spent":"320.306964ms","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:54:36.433982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.421847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.433997Z","caller":"traceutil/trace.go:171","msg":"trace[235751628] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:573; }","duration":"320.437357ms","start":"2026-02-01T18:54:36.113555Z","end":"2026-02-01T18:54:36.433992Z","steps":["trace[235751628] 'agreement among raft nodes before linearized reading' (duration: 320.413807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.434014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.113538Z","time spent":"320.469947ms","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:54:36.43416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.640761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.434226Z","caller":"traceutil/trace.go:171","msg":"trace[1538677933] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:573; }","duration":"320.739863ms","start":"2026-02-01T18:54:36.113476Z","end":"2026-02-01T18:54:36.434215Z","steps":["trace[1538677933] 'agreement among raft nodes before linearized reading' (duration: 320.564049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.434259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.113441Z","time spent":"320.809174ms","remote":"127.0.0.1:54638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:54:36.434402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.442208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.434428Z","caller":"traceutil/trace.go:171","msg":"trace[1692078414] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:573; }","duration":"338.496779ms","start":"2026-02-01T18:54:36.095923Z","end":"2026-02-01T18:54:36.43442Z","steps":["trace[1692078414] 'agreement among raft nodes before linearized reading' (duration: 338.425347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.434484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.095907Z","time spent":"338.568661ms","remote":"127.0.0.1:54618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 "} {"level":"info","ts":"2026-02-01T18:54:36.943816Z","caller":"traceutil/trace.go:171","msg":"trace[878592870] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"502.397556ms","start":"2026-02-01T18:54:36.44137Z","end":"2026-02-01T18:54:36.943768Z","steps":["trace[878592870] 'process raft request' (duration: 478.394937ms)","trace[878592870] 'compare' (duration: 23.892997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:36.944381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.441362Z","time spent":"502.940467ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:36.944769Z","caller":"traceutil/trace.go:171","msg":"trace[497044171] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:592; }","duration":"334.554675ms","start":"2026-02-01T18:54:36.610201Z","end":"2026-02-01T18:54:36.944755Z","steps":["trace[497044171] 'read index received' (duration: 309.573366ms)","trace[497044171] 'applied index is now lower than readState.Index' (duration: 24.980429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:36.945143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.907043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.947087Z","caller":"traceutil/trace.go:171","msg":"trace[1379739314] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:577; }","duration":"336.897085ms","start":"2026-02-01T18:54:36.610179Z","end":"2026-02-01T18:54:36.947076Z","steps":["trace[1379739314] 'agreement among raft nodes before linearized reading' (duration: 334.78307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.94713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.610166Z","time spent":"336.950216ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-01T18:54:36.947282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.003857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.945245Z","caller":"traceutil/trace.go:171","msg":"trace[1328175476] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"501.313822ms","start":"2026-02-01T18:54:36.443909Z","end":"2026-02-01T18:54:36.945223Z","steps":["trace[1328175476] 'process raft request' (duration: 500.779901ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.94529Z","caller":"traceutil/trace.go:171","msg":"trace[1757717576] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"498.062762ms","start":"2026-02-01T18:54:36.447217Z","end":"2026-02-01T18:54:36.94528Z","steps":["trace[1757717576] 'process raft request' (duration: 497.505141ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.945706Z","caller":"traceutil/trace.go:171","msg":"trace[1082087954] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"503.361845ms","start":"2026-02-01T18:54:36.441559Z","end":"2026-02-01T18:54:36.944921Z","steps":["trace[1082087954] 'process raft request' (duration: 503.050288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.947458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.379502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.948233Z","caller":"traceutil/trace.go:171","msg":"trace[1705074073] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:577; }","duration":"286.164349ms","start":"2026-02-01T18:54:36.662055Z","end":"2026-02-01T18:54:36.94822Z","steps":["trace[1705074073] 'agreement among raft nodes before linearized reading' (duration: 285.373522ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.948503Z","caller":"traceutil/trace.go:171","msg":"trace[1703037920] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:577; }","duration":"338.226622ms","start":"2026-02-01T18:54:36.610265Z","end":"2026-02-01T18:54:36.948491Z","steps":["trace[1703037920] 'agreement among raft nodes before linearized reading' (duration: 336.978116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.948644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.610262Z","time spent":"338.368496ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:54:36.948806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.443902Z","time spent":"504.875318ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:36.948997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.44721Z","time spent":"501.763941ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:36.949207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.441545Z","time spent":"507.639056ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:36.947502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.509156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.949537Z","caller":"traceutil/trace.go:171","msg":"trace[594817728] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:577; }","duration":"287.540189ms","start":"2026-02-01T18:54:36.661988Z","end":"2026-02-01T18:54:36.949528Z","steps":["trace[594817728] 'agreement among raft nodes before linearized reading' (duration: 285.489575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.947526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.577147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.950007Z","caller":"traceutil/trace.go:171","msg":"trace[357629633] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:577; }","duration":"288.08936ms","start":"2026-02-01T18:54:36.661909Z","end":"2026-02-01T18:54:36.949998Z","steps":["trace[357629633] 'agreement among raft nodes before linearized reading' (duration: 285.605317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.947549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.599478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.120894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.154955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.195496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.83751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.94766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.895132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.988414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.077788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.111409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.189471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:36.947767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.485617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:36.952492Z","caller":"traceutil/trace.go:171","msg":"trace[293585666] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:577; }","duration":"290.545213ms","start":"2026-02-01T18:54:36.661936Z","end":"2026-02-01T18:54:36.952481Z","steps":["trace[293585666] 'agreement among raft nodes before linearized reading' (duration: 285.599917ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.952676Z","caller":"traceutil/trace.go:171","msg":"trace[1688261650] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:577; }","duration":"296.228342ms","start":"2026-02-01T18:54:36.656441Z","end":"2026-02-01T18:54:36.952669Z","steps":["trace[1688261650] 'agreement among raft nodes before linearized reading' (duration: 291.117674ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.952696Z","caller":"traceutil/trace.go:171","msg":"trace[1729107612] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:577; }","duration":"296.261894ms","start":"2026-02-01T18:54:36.656429Z","end":"2026-02-01T18:54:36.952691Z","steps":["trace[1729107612] 'agreement among raft nodes before linearized reading' (duration: 291.152845ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.953707Z","caller":"traceutil/trace.go:171","msg":"trace[1010708854] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:577; }","duration":"297.290515ms","start":"2026-02-01T18:54:36.656407Z","end":"2026-02-01T18:54:36.953698Z","steps":["trace[1010708854] 'agreement among raft nodes before linearized reading' (duration: 291.197846ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:36.95414Z","caller":"traceutil/trace.go:171","msg":"trace[463609693] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:577; }","duration":"326.598347ms","start":"2026-02-01T18:54:36.627537Z","end":"2026-02-01T18:54:36.954136Z","steps":["trace[463609693] 'agreement among raft nodes before linearized reading' (duration: 320.199651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.954185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.627512Z","time spent":"326.655119ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-02-01T18:54:36.953911Z","caller":"traceutil/trace.go:171","msg":"trace[761675017] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:577; }","duration":"316.062022ms","start":"2026-02-01T18:54:36.637789Z","end":"2026-02-01T18:54:36.953851Z","steps":["trace[761675017] 'agreement among raft nodes before linearized reading' (duration: 309.83709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.954406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.637787Z","time spent":"316.606715ms","remote":"127.0.0.1:54674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"info","ts":"2026-02-01T18:54:36.953936Z","caller":"traceutil/trace.go:171","msg":"trace[30841544] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:577; }","duration":"316.189185ms","start":"2026-02-01T18:54:36.63774Z","end":"2026-02-01T18:54:36.953929Z","steps":["trace[30841544] 'agreement among raft nodes before linearized reading' (duration: 309.906632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.95459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.637737Z","time spent":"316.84424ms","remote":"127.0.0.1:54674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:54:36.953955Z","caller":"traceutil/trace.go:171","msg":"trace[1002800111] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:577; }","duration":"316.282897ms","start":"2026-02-01T18:54:36.637667Z","end":"2026-02-01T18:54:36.95395Z","steps":["trace[1002800111] 'agreement among raft nodes before linearized reading' (duration: 310.003234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.954754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.637661Z","time spent":"317.078775ms","remote":"127.0.0.1:54674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-02-01T18:54:36.95398Z","caller":"traceutil/trace.go:171","msg":"trace[2084597616] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:577; }","duration":"326.355001ms","start":"2026-02-01T18:54:36.627615Z","end":"2026-02-01T18:54:36.95397Z","steps":["trace[2084597616] 'agreement among raft nodes before linearized reading' (duration: 320.075878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.954892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.627613Z","time spent":"327.271912ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:54:36.954101Z","caller":"traceutil/trace.go:171","msg":"trace[2089701951] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:577; }","duration":"326.507144ms","start":"2026-02-01T18:54:36.627588Z","end":"2026-02-01T18:54:36.954095Z","steps":["trace[2089701951] 'agreement among raft nodes before linearized reading' (duration: 320.124249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.954993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.627585Z","time spent":"327.402464ms","remote":"127.0.0.1:54670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"info","ts":"2026-02-01T18:54:36.954123Z","caller":"traceutil/trace.go:171","msg":"trace[233344664] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:577; }","duration":"343.847352ms","start":"2026-02-01T18:54:36.610271Z","end":"2026-02-01T18:54:36.954118Z","steps":["trace[233344664] 'agreement among raft nodes before linearized reading' (duration: 337.486187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:36.955084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:36.610269Z","time spent":"344.808023ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-01T18:54:41.326563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624274605042144,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:54:41.709817Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.2517848s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:54:41.710155Z","caller":"traceutil/trace.go:171","msg":"trace[325577535] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"883.6344ms","start":"2026-02-01T18:54:40.826501Z","end":"2026-02-01T18:54:41.710135Z","steps":["trace[325577535] 'read index received' (duration: 883.466457ms)","trace[325577535] 'applied index is now lower than readState.Index' (duration: 166.593µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:41.710262Z","caller":"traceutil/trace.go:171","msg":"trace[484133462] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.252312401s","start":"2026-02-01T18:54:40.457941Z","end":"2026-02-01T18:54:41.710254Z","steps":["trace[484133462] 'process raft request' (duration: 1.252049335s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.710342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:40.457926Z","time spent":"1.252354782s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:41.710456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.954657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:41.710477Z","caller":"traceutil/trace.go:171","msg":"trace[1546325138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"884.008678ms","start":"2026-02-01T18:54:40.826462Z","end":"2026-02-01T18:54:41.71047Z","steps":["trace[1546325138] 'agreement among raft nodes before linearized reading' (duration: 883.971268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.710493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:40.826441Z","time spent":"884.04899ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:41.71076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.211029ms","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-01T18:54:41.710781Z","caller":"traceutil/trace.go:171","msg":"trace[561255483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"749.267021ms","start":"2026-02-01T18:54:40.961506Z","end":"2026-02-01T18:54:41.710773Z","steps":["trace[561255483] 'agreement among raft nodes before linearized reading' (duration: 749.163289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.710798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:40.961485Z","time spent":"749.308022ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:54:41.710842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.239091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:54:41.710889Z","caller":"traceutil/trace.go:171","msg":"trace[1370910489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:585; }","duration":"742.309154ms","start":"2026-02-01T18:54:40.968559Z","end":"2026-02-01T18:54:41.710869Z","steps":["trace[1370910489] 'agreement among raft nodes before linearized reading' (duration: 742.125929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.710922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:40.968557Z","time spent":"742.357945ms","remote":"127.0.0.1:51098","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-01T18:54:41.710957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.409946ms","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-01T18:54:41.71098Z","caller":"traceutil/trace.go:171","msg":"trace[1877080944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"742.444067ms","start":"2026-02-01T18:54:40.968529Z","end":"2026-02-01T18:54:41.710973Z","steps":["trace[1877080944] 'agreement among raft nodes before linearized reading' (duration: 742.368735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.710997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:40.968525Z","time spent":"742.468407ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:41.711125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.220324ms","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-01T18:54:41.711144Z","caller":"traceutil/trace.go:171","msg":"trace[1321780572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"743.264514ms","start":"2026-02-01T18:54:40.967872Z","end":"2026-02-01T18:54:41.711137Z","steps":["trace[1321780572] 'agreement among raft nodes before linearized reading' (duration: 743.210253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:41.71116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:40.967858Z","time spent":"743.297966ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:54:42.641543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.351078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:42.64177Z","caller":"traceutil/trace.go:171","msg":"trace[1670744486] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"911.806779ms","start":"2026-02-01T18:54:41.729946Z","end":"2026-02-01T18:54:42.641753Z","steps":["trace[1670744486] 'process raft request' (duration: 911.778058ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:42.641749Z","caller":"traceutil/trace.go:171","msg":"trace[1904444186] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"915.792073ms","start":"2026-02-01T18:54:41.725917Z","end":"2026-02-01T18:54:42.641709Z","steps":["trace[1904444186] 'process raft request' (duration: 758.1995ms)","trace[1904444186] 'compare' (duration: 157.220885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:42.641823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.729935Z","time spent":"911.86065ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:42.641915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.725883Z","time spent":"915.935666ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:42.64204Z","caller":"traceutil/trace.go:171","msg":"trace[740962082] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"915.810954ms","start":"2026-02-01T18:54:41.726222Z","end":"2026-02-01T18:54:42.642033Z","steps":["trace[740962082] 'process raft request' (duration: 915.417455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.642077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.726212Z","time spent":"915.846534ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:42.642161Z","caller":"traceutil/trace.go:171","msg":"trace[530910994] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"914.451914ms","start":"2026-02-01T18:54:41.727701Z","end":"2026-02-01T18:54:42.642153Z","steps":["trace[530910994] 'process raft request' (duration: 913.982974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:42.642209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:41.727693Z","time spent":"914.492545ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:49.511783Z","caller":"traceutil/trace.go:171","msg":"trace[2034901763] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"180.726702ms","start":"2026-02-01T18:54:49.331038Z","end":"2026-02-01T18:54:49.511765Z","steps":["trace[2034901763] 'process raft request' (duration: 180.6184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:50.489543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.52261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:54:50.489633Z","caller":"traceutil/trace.go:171","msg":"trace[1305198682] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:639; }","duration":"297.631973ms","start":"2026-02-01T18:54:50.191956Z","end":"2026-02-01T18:54:50.489588Z","steps":["trace[1305198682] 'range keys from in-memory index tree' (duration: 297.280215ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:52.140387Z","caller":"traceutil/trace.go:171","msg":"trace[1171993193] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"341.664366ms","start":"2026-02-01T18:54:51.798705Z","end":"2026-02-01T18:54:52.140369Z","steps":["trace[1171993193] 'read index received' (duration: 341.507182ms)","trace[1171993193] 'applied index is now lower than readState.Index' (duration: 156.444µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:52.140568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.84938ms","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-01T18:54:52.140586Z","caller":"traceutil/trace.go:171","msg":"trace[455864615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"341.906112ms","start":"2026-02-01T18:54:51.798674Z","end":"2026-02-01T18:54:52.14058Z","steps":["trace[455864615] 'agreement among raft nodes before linearized reading' (duration: 341.79966ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:52.140545Z","caller":"traceutil/trace.go:171","msg":"trace[2081370075] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"546.353178ms","start":"2026-02-01T18:54:51.594126Z","end":"2026-02-01T18:54:52.140479Z","steps":["trace[2081370075] 'process raft request' (duration: 546.145482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:52.140605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:51.798662Z","time spent":"341.938792ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:52.140742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.685606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:52.140754Z","caller":"traceutil/trace.go:171","msg":"trace[544655714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"315.720946ms","start":"2026-02-01T18:54:51.82503Z","end":"2026-02-01T18:54:52.140751Z","steps":["trace[544655714] 'agreement among raft nodes before linearized reading' (duration: 315.700216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:52.140766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:51.825016Z","time spent":"315.747767ms","remote":"127.0.0.1:50784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:52.140751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:51.594117Z","time spent":"546.529281ms","remote":"127.0.0.1:50872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:52.60643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.220779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:54:52.606519Z","caller":"traceutil/trace.go:171","msg":"trace[439054808] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"462.744074ms","start":"2026-02-01T18:54:52.143746Z","end":"2026-02-01T18:54:52.60649Z","steps":["trace[439054808] 'process raft request' (duration: 86.395232ms)","trace[439054808] 'compare' (duration: 375.957294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:52.606562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:52.143734Z","time spent":"462.807875ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:54.61344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015570689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xcm87\" ","response":"range_response_count:1 size:18246"} {"level":"info","ts":"2026-02-01T18:54:54.613504Z","caller":"traceutil/trace.go:171","msg":"trace[140140473] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xcm87; range_end:; response_count:1; response_revision:650; }","duration":"1.015664631s","start":"2026-02-01T18:54:53.597825Z","end":"2026-02-01T18:54:54.613489Z","steps":["trace[140140473] 'range keys from in-memory index tree' (duration: 1.015463156s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.613539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:53.597811Z","time spent":"1.015720712s","remote":"127.0.0.1:51004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18270,"request content":"key:\"/registry/pods/kube-system/cilium-xcm87\" "} {"level":"warn","ts":"2026-02-01T18:54:54.614376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.468945ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:54.614453Z","caller":"traceutil/trace.go:171","msg":"trace[1394185165] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:650; }","duration":"169.544106ms","start":"2026-02-01T18:54:54.444896Z","end":"2026-02-01T18:54:54.61444Z","steps":["trace[1394185165] 'range keys from in-memory index tree' (duration: 169.461804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.61462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851681262s","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-01T18:54:54.615528Z","caller":"traceutil/trace.go:171","msg":"trace[1883539022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"1.852620162s","start":"2026-02-01T18:54:52.762891Z","end":"2026-02-01T18:54:54.615511Z","steps":["trace[1883539022] 'range keys from in-memory index tree' (duration: 1.850502867s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.615618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:52.762875Z","time spent":"1.852733784s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:54:54.6149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.835696203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:54:54.615856Z","caller":"traceutil/trace.go:171","msg":"trace[893070167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:650; }","duration":"1.836676283s","start":"2026-02-01T18:54:52.779172Z","end":"2026-02-01T18:54:54.615849Z","steps":["trace[893070167] 'range keys from in-memory index tree' (duration: 1.835515589s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.615958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:52.779154Z","time spent":"1.836793687s","remote":"127.0.0.1:51098","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-01T18:54:54.61496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.835578451s","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-01T18:54:54.61644Z","caller":"traceutil/trace.go:171","msg":"trace[177953552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"1.837071352s","start":"2026-02-01T18:54:52.77936Z","end":"2026-02-01T18:54:54.616432Z","steps":["trace[177953552] 'range keys from in-memory index tree' (duration: 1.835520359s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.615021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830553975s","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-01T18:54:54.616558Z","caller":"traceutil/trace.go:171","msg":"trace[1092561606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"1.832092887s","start":"2026-02-01T18:54:52.784451Z","end":"2026-02-01T18:54:54.616544Z","steps":["trace[1092561606] 'range keys from in-memory index tree' (duration: 1.830498343s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.616609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:52.784446Z","time spent":"1.832148238s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:54.616689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:52.779355Z","time spent":"1.837174784s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:54:54.615112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793896287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:54.616803Z","caller":"traceutil/trace.go:171","msg":"trace[416696563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"1.795607413s","start":"2026-02-01T18:54:52.821189Z","end":"2026-02-01T18:54:54.616796Z","steps":["trace[416696563] 'range keys from in-memory index tree' (duration: 1.793853016s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.616965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:52.821177Z","time spent":"1.795780667s","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:54:54.758553Z","caller":"traceutil/trace.go:171","msg":"trace[1753802016] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"662.761076ms","start":"2026-02-01T18:54:54.095774Z","end":"2026-02-01T18:54:54.758535Z","steps":["trace[1753802016] 'process raft request' (duration: 662.658454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.758662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:54.095759Z","time spent":"662.846888ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:54.759033Z","caller":"traceutil/trace.go:171","msg":"trace[2071440868] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:674; }","duration":"148.201003ms","start":"2026-02-01T18:54:54.610825Z","end":"2026-02-01T18:54:54.759026Z","steps":["trace[2071440868] 'read index received' (duration: 148.198143ms)","trace[2071440868] 'applied index is now lower than readState.Index' (duration: 2.34µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:54:54.760412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.582313ms","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-01T18:54:54.76044Z","caller":"traceutil/trace.go:171","msg":"trace[1021511289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"149.646245ms","start":"2026-02-01T18:54:54.610787Z","end":"2026-02-01T18:54:54.760433Z","steps":["trace[1021511289] 'agreement among raft nodes before linearized reading' (duration: 149.553523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:54.765271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.829464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:54:54.765315Z","caller":"traceutil/trace.go:171","msg":"trace[2111699950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"145.888765ms","start":"2026-02-01T18:54:54.619416Z","end":"2026-02-01T18:54:54.765305Z","steps":["trace[2111699950] 'agreement among raft nodes before linearized reading' (duration: 145.818803ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:54.767131Z","caller":"traceutil/trace.go:171","msg":"trace[920398173] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"142.804729ms","start":"2026-02-01T18:54:54.624316Z","end":"2026-02-01T18:54:54.767121Z","steps":["trace[920398173] 'process raft request' (duration: 140.710104ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:54.767307Z","caller":"traceutil/trace.go:171","msg":"trace[217635138] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"142.923741ms","start":"2026-02-01T18:54:54.624375Z","end":"2026-02-01T18:54:54.767299Z","steps":["trace[217635138] 'process raft request' (duration: 140.723715ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:54.76743Z","caller":"traceutil/trace.go:171","msg":"trace[169800795] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"142.036582ms","start":"2026-02-01T18:54:54.625389Z","end":"2026-02-01T18:54:54.767425Z","steps":["trace[169800795] 'process raft request' (duration: 139.736773ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:54.767528Z","caller":"traceutil/trace.go:171","msg":"trace[639038421] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"142.085743ms","start":"2026-02-01T18:54:54.625437Z","end":"2026-02-01T18:54:54.767523Z","steps":["trace[639038421] 'process raft request' (duration: 139.715123ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:54.767642Z","caller":"traceutil/trace.go:171","msg":"trace[137049693] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"135.600496ms","start":"2026-02-01T18:54:54.632037Z","end":"2026-02-01T18:54:54.767638Z","steps":["trace[137049693] 'process raft request' (duration: 133.142963ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:56.342075Z","caller":"traceutil/trace.go:171","msg":"trace[1399381151] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"215.656566ms","start":"2026-02-01T18:54:56.126399Z","end":"2026-02-01T18:54:56.342056Z","steps":["trace[1399381151] 'process raft request' (duration: 214.597414ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:59.279298Z","caller":"traceutil/trace.go:171","msg":"trace[1469975717] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"490.06115ms","start":"2026-02-01T18:54:58.78922Z","end":"2026-02-01T18:54:59.279281Z","steps":["trace[1469975717] 'process raft request' (duration: 489.978159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:59.279395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:58.789202Z","time spent":"490.141582ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:54:59.860665Z","caller":"traceutil/trace.go:171","msg":"trace[818202128] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"1.068981027s","start":"2026-02-01T18:54:58.791669Z","end":"2026-02-01T18:54:59.86065Z","steps":["trace[818202128] 'read index received' (duration: 487.612077ms)","trace[818202128] 'applied index is now lower than readState.Index' (duration: 581.36833ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:54:59.860739Z","caller":"traceutil/trace.go:171","msg":"trace[135824173] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.071263587s","start":"2026-02-01T18:54:58.789468Z","end":"2026-02-01T18:54:59.860732Z","steps":["trace[135824173] 'process raft request' (duration: 1.071091463s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:59.860806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:58.78946Z","time spent":"1.071296197s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:54:59.860878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06544289s","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-01T18:54:59.860907Z","caller":"traceutil/trace.go:171","msg":"trace[2034924561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:716; }","duration":"1.06547292s","start":"2026-02-01T18:54:58.795425Z","end":"2026-02-01T18:54:59.860898Z","steps":["trace[2034924561] 'agreement among raft nodes before linearized reading' (duration: 1.065371678s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:59.860926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:58.79541Z","time spent":"1.065512023s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:54:59.860993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.752388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:54:59.861014Z","caller":"traceutil/trace.go:171","msg":"trace[2008430002] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:716; }","duration":"647.804709ms","start":"2026-02-01T18:54:59.213202Z","end":"2026-02-01T18:54:59.861007Z","steps":["trace[2008430002] 'agreement among raft nodes before linearized reading' (duration: 647.770588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:59.86104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039759111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:54:59.861054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:59.213188Z","time spent":"647.860111ms","remote":"127.0.0.1:51276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:54:59.861058Z","caller":"traceutil/trace.go:171","msg":"trace[1981284887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"1.039802773s","start":"2026-02-01T18:54:58.821249Z","end":"2026-02-01T18:54:59.861052Z","steps":["trace[1981284887] 'agreement among raft nodes before linearized reading' (duration: 1.039760602s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:59.861074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:58.821245Z","time spent":"1.039825503s","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:54:59.861174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069503659s","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-01T18:54:59.861179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040891255s","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-01T18:54:59.861191Z","caller":"traceutil/trace.go:171","msg":"trace[1816852626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:716; }","duration":"1.069540239s","start":"2026-02-01T18:54:58.791646Z","end":"2026-02-01T18:54:59.861186Z","steps":["trace[1816852626] 'agreement among raft nodes before linearized reading' (duration: 1.069487588s)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:54:59.861195Z","caller":"traceutil/trace.go:171","msg":"trace[1434310286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:716; }","duration":"1.040922297s","start":"2026-02-01T18:54:58.820268Z","end":"2026-02-01T18:54:59.86119Z","steps":["trace[1434310286] 'agreement among raft nodes before linearized reading' (duration: 1.040874856s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:54:59.861207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:58.791636Z","time spent":"1.06956717s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:54:59.86121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:58.820261Z","time spent":"1.040945657s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:00.371355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624274605042385,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T18:55:00.613464Z","caller":"traceutil/trace.go:171","msg":"trace[925127259] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"746.532339ms","start":"2026-02-01T18:54:59.866905Z","end":"2026-02-01T18:55:00.613437Z","steps":["trace[925127259] 'process raft request' (duration: 708.085531ms)","trace[925127259] 'compare' (duration: 38.198193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:00.613631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:59.866892Z","time spent":"746.633391ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:00.663574Z","caller":"traceutil/trace.go:171","msg":"trace[655871790] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:740; }","duration":"792.746557ms","start":"2026-02-01T18:54:59.870809Z","end":"2026-02-01T18:55:00.663556Z","steps":["trace[655871790] 'read index received' (duration: 704.189096ms)","trace[655871790] 'applied index is now lower than readState.Index' (duration: 88.556821ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:00.663815Z","caller":"traceutil/trace.go:171","msg":"trace[1134538743] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"796.790814ms","start":"2026-02-01T18:54:59.867015Z","end":"2026-02-01T18:55:00.663805Z","steps":["trace[1134538743] 'process raft request' (duration: 796.398316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:00.66389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:59.86701Z","time spent":"796.827196ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:00.66401Z","caller":"traceutil/trace.go:171","msg":"trace[572542101] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"796.491468ms","start":"2026-02-01T18:54:59.86751Z","end":"2026-02-01T18:55:00.664002Z","steps":["trace[572542101] 'process raft request' (duration: 795.993428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:00.664043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:59.867504Z","time spent":"796.520119ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:00.664141Z","caller":"traceutil/trace.go:171","msg":"trace[669410895] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"447.713078ms","start":"2026-02-01T18:55:00.216419Z","end":"2026-02-01T18:55:00.664132Z","steps":["trace[669410895] 'process raft request' (duration: 447.109325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:00.664183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:00.216402Z","time spent":"447.756969ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:00.664314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.500463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:00.664342Z","caller":"traceutil/trace.go:171","msg":"trace[1344243365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"793.525093ms","start":"2026-02-01T18:54:59.870807Z","end":"2026-02-01T18:55:00.664332Z","steps":["trace[1344243365] 'agreement among raft nodes before linearized reading' (duration: 793.484273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:00.664363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:54:59.870789Z","time spent":"793.569065ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:00.664532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.110419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:55:00.664557Z","caller":"traceutil/trace.go:171","msg":"trace[592421817] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:720; }","duration":"472.17143ms","start":"2026-02-01T18:55:00.192379Z","end":"2026-02-01T18:55:00.66455Z","steps":["trace[592421817] 'agreement among raft nodes before linearized reading' (duration: 472.084688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:00.66458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:00.19236Z","time spent":"472.215661ms","remote":"127.0.0.1:50806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.65\" "} {"level":"warn","ts":"2026-02-01T18:55:00.664823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.180649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:00.664852Z","caller":"traceutil/trace.go:171","msg":"trace[1125390114] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:720; }","duration":"198.2414ms","start":"2026-02-01T18:55:00.466604Z","end":"2026-02-01T18:55:00.664845Z","steps":["trace[1125390114] 'agreement among raft nodes before linearized reading' (duration: 198.175019ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:01.194278Z","caller":"traceutil/trace.go:171","msg":"trace[1248151019] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"484.033188ms","start":"2026-02-01T18:55:00.710225Z","end":"2026-02-01T18:55:01.194258Z","steps":["trace[1248151019] 'process raft request' (duration: 482.995555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:01.194445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:00.710206Z","time spent":"484.157331ms","remote":"127.0.0.1:50806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:02.98626Z","caller":"traceutil/trace.go:171","msg":"trace[1996644936] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"297.165806ms","start":"2026-02-01T18:55:02.68908Z","end":"2026-02-01T18:55:02.986246Z","steps":["trace[1996644936] 'read index received' (duration: 297.036744ms)","trace[1996644936] 'applied index is now lower than readState.Index' (duration: 128.562µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:02.986398Z","caller":"traceutil/trace.go:171","msg":"trace[1161082135] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"366.124799ms","start":"2026-02-01T18:55:02.620268Z","end":"2026-02-01T18:55:02.986393Z","steps":["trace[1161082135] 'process raft request' (duration: 365.900894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.986461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.620257Z","time spent":"366.16085ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:02.9866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.518944ms","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-01T18:55:02.986616Z","caller":"traceutil/trace.go:171","msg":"trace[1112201515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"297.554424ms","start":"2026-02-01T18:55:02.689057Z","end":"2026-02-01T18:55:02.986611Z","steps":["trace[1112201515] 'agreement among raft nodes before linearized reading' (duration: 297.486693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.986704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.325639ms","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-01T18:55:02.986717Z","caller":"traceutil/trace.go:171","msg":"trace[200310738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:724; }","duration":"297.34023ms","start":"2026-02-01T18:55:02.689374Z","end":"2026-02-01T18:55:02.986714Z","steps":["trace[200310738] 'agreement among raft nodes before linearized reading' (duration: 297.303599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:02.986805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.76987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:02.986817Z","caller":"traceutil/trace.go:171","msg":"trace[647997646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"164.799931ms","start":"2026-02-01T18:55:02.822013Z","end":"2026-02-01T18:55:02.986813Z","steps":["trace[647997646] 'agreement among raft nodes before linearized reading' (duration: 164.78147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:03.411993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.925674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:03.412125Z","caller":"traceutil/trace.go:171","msg":"trace[2134451591] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"422.445247ms","start":"2026-02-01T18:55:02.989666Z","end":"2026-02-01T18:55:03.412111Z","steps":["trace[2134451591] 'process raft request' (duration: 207.207806ms)","trace[2134451591] 'compare' (duration: 214.581897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:03.412175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.989656Z","time spent":"422.499718ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:03.412586Z","caller":"traceutil/trace.go:171","msg":"trace[2046717436] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"422.008767ms","start":"2026-02-01T18:55:02.990571Z","end":"2026-02-01T18:55:03.41258Z","steps":["trace[2046717436] 'process raft request' (duration: 421.498546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:03.412622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:02.990563Z","time spent":"422.036937ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:03.413074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.163553ms","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-01T18:55:03.413099Z","caller":"traceutil/trace.go:171","msg":"trace[442987486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"122.219894ms","start":"2026-02-01T18:55:03.290872Z","end":"2026-02-01T18:55:03.413092Z","steps":["trace[442987486] 'agreement among raft nodes before linearized reading' (duration: 122.110781ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:03.412478Z","caller":"traceutil/trace.go:171","msg":"trace[1910975901] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"121.561149ms","start":"2026-02-01T18:55:03.29091Z","end":"2026-02-01T18:55:03.412471Z","steps":["trace[1910975901] 'read index received' (duration: 120.466545ms)","trace[1910975901] 'applied index is now lower than readState.Index' (duration: 1.094064ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:03.533021Z","caller":"traceutil/trace.go:171","msg":"trace[2111237232] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"113.36795ms","start":"2026-02-01T18:55:03.419637Z","end":"2026-02-01T18:55:03.533005Z","steps":["trace[2111237232] 'process raft request' (duration: 112.276527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.149337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.409563ms","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-01T18:55:14.149386Z","caller":"traceutil/trace.go:171","msg":"trace[872705405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"682.485994ms","start":"2026-02-01T18:55:13.466888Z","end":"2026-02-01T18:55:14.149374Z","steps":["trace[872705405] 'range keys from in-memory index tree' (duration: 682.319841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.149407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.466876Z","time spent":"682.526795ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:14.149618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.639287ms","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-01T18:55:14.149634Z","caller":"traceutil/trace.go:171","msg":"trace[1316984055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"615.685439ms","start":"2026-02-01T18:55:13.533943Z","end":"2026-02-01T18:55:14.149629Z","steps":["trace[1316984055] 'range keys from in-memory index tree' (duration: 615.545895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.149646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.533929Z","time spent":"615.714139ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:14.149792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.078196ms","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-01T18:55:14.149817Z","caller":"traceutil/trace.go:171","msg":"trace[1047493437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"571.140546ms","start":"2026-02-01T18:55:13.578672Z","end":"2026-02-01T18:55:14.149812Z","steps":["trace[1047493437] 'range keys from in-memory index tree' (duration: 570.910392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:14.14983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:13.578651Z","time spent":"571.176338ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:14.150215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.240394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:55:14.15023Z","caller":"traceutil/trace.go:171","msg":"trace[1751461475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"184.284545ms","start":"2026-02-01T18:55:13.965941Z","end":"2026-02-01T18:55:14.150225Z","steps":["trace[1751461475] 'range keys from in-memory index tree' (duration: 184.131262ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:18.67192Z","caller":"traceutil/trace.go:171","msg":"trace[174108887] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"423.624023ms","start":"2026-02-01T18:55:18.24828Z","end":"2026-02-01T18:55:18.671904Z","steps":["trace[174108887] 'read index received' (duration: 423.51007ms)","trace[174108887] 'applied index is now lower than readState.Index' (duration: 113.013µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:18.671999Z","caller":"traceutil/trace.go:171","msg":"trace[1453113921] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"451.007171ms","start":"2026-02-01T18:55:18.220987Z","end":"2026-02-01T18:55:18.671994Z","steps":["trace[1453113921] 'process raft request' (duration: 450.815227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.672067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:18.220975Z","time spent":"451.039291ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:18.672215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.590931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-01T18:55:18.672259Z","caller":"traceutil/trace.go:171","msg":"trace[1781524219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"422.665912ms","start":"2026-02-01T18:55:18.249581Z","end":"2026-02-01T18:55:18.672247Z","steps":["trace[1781524219] 'agreement among raft nodes before linearized reading' (duration: 422.54605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.67228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:18.24957Z","time spent":"422.706304ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:18.672361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.955706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-01T18:55:18.672383Z","caller":"traceutil/trace.go:171","msg":"trace[150492260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"420.986487ms","start":"2026-02-01T18:55:18.25139Z","end":"2026-02-01T18:55:18.672377Z","steps":["trace[150492260] 'agreement among raft nodes before linearized reading' (duration: 420.898765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.672395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.575359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-01T18:55:18.672397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:18.251386Z","time spent":"421.008087ms","remote":"127.0.0.1:51098","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-02-01T18:55:18.67241Z","caller":"traceutil/trace.go:171","msg":"trace[124558708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"421.59912ms","start":"2026-02-01T18:55:18.250807Z","end":"2026-02-01T18:55:18.672406Z","steps":["trace[124558708] 'agreement among raft nodes before linearized reading' (duration: 421.560079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.672423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:18.250802Z","time spent":"421.61848ms","remote":"127.0.0.1:51098","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-01T18:55:18.672472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.190746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:18.672483Z","caller":"traceutil/trace.go:171","msg":"trace[1562518769] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:770; }","duration":"424.203176ms","start":"2026-02-01T18:55:18.248276Z","end":"2026-02-01T18:55:18.672479Z","steps":["trace[1562518769] 'agreement among raft nodes before linearized reading' (duration: 424.182966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:18.672498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:18.248238Z","time spent":"424.253327ms","remote":"127.0.0.1:50960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:25.192201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.070788ms","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-01T18:55:25.192298Z","caller":"traceutil/trace.go:171","msg":"trace[531180811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"416.15617ms","start":"2026-02-01T18:55:24.776125Z","end":"2026-02-01T18:55:25.192281Z","steps":["trace[531180811] 'range keys from in-memory index tree' (duration: 414.935475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.192343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:24.776111Z","time spent":"416.218902ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:25.193025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.552787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:55:25.193084Z","caller":"traceutil/trace.go:171","msg":"trace[845607921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"393.626929ms","start":"2026-02-01T18:55:24.799428Z","end":"2026-02-01T18:55:25.193055Z","steps":["trace[845607921] 'range keys from in-memory index tree' (duration: 393.316392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.193117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:24.799411Z","time spent":"393.69642ms","remote":"127.0.0.1:51098","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-01T18:55:25.193421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.135106ms","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-01T18:55:25.193453Z","caller":"traceutil/trace.go:171","msg":"trace[381930152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"392.193578ms","start":"2026-02-01T18:55:24.801251Z","end":"2026-02-01T18:55:25.193444Z","steps":["trace[381930152] 'range keys from in-memory index tree' (duration: 391.978773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.193483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:24.801241Z","time spent":"392.233259ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-01T18:55:25.53775Z","caller":"traceutil/trace.go:171","msg":"trace[137487285] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"333.089649ms","start":"2026-02-01T18:55:25.204641Z","end":"2026-02-01T18:55:25.537731Z","steps":["trace[137487285] 'process raft request' (duration: 327.769833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.538138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:25.204626Z","time spent":"333.468887ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:25.538399Z","caller":"traceutil/trace.go:171","msg":"trace[1961684872] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"332.351824ms","start":"2026-02-01T18:55:25.206038Z","end":"2026-02-01T18:55:25.53839Z","steps":["trace[1961684872] 'process raft request' (duration: 331.608097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:25.538472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:25.206033Z","time spent":"332.413935ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:25.537915Z","caller":"traceutil/trace.go:171","msg":"trace[356293075] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:816; }","duration":"286.193327ms","start":"2026-02-01T18:55:25.251714Z","end":"2026-02-01T18:55:25.537907Z","steps":["trace[356293075] 'read index received' (duration: 29.493343ms)","trace[356293075] 'applied index is now lower than readState.Index' (duration: 256.699354ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:25.538069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-01T18:55:25.538649Z","caller":"traceutil/trace.go:171","msg":"trace[1036688609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"286.952845ms","start":"2026-02-01T18:55:25.251687Z","end":"2026-02-01T18:55:25.53864Z","steps":["trace[1036688609] 'agreement among raft nodes before linearized reading' (duration: 286.269379ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:25.553696Z","caller":"traceutil/trace.go:171","msg":"trace[1695267433] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"223.243935ms","start":"2026-02-01T18:55:25.330437Z","end":"2026-02-01T18:55:25.553681Z","steps":["trace[1695267433] 'process raft request' (duration: 222.898418ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:37.586825Z","caller":"traceutil/trace.go:171","msg":"trace[924424860] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"262.835717ms","start":"2026-02-01T18:55:37.323973Z","end":"2026-02-01T18:55:37.586809Z","steps":["trace[924424860] 'process raft request' (duration: 262.721585ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:37.698176Z","caller":"traceutil/trace.go:171","msg":"trace[398395214] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"101.505832ms","start":"2026-02-01T18:55:37.596646Z","end":"2026-02-01T18:55:37.698152Z","steps":["trace[398395214] 'process raft request' (duration: 23.568523ms)","trace[398395214] 'compare' (duration: 77.700394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:38.051705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.909517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:38.051775Z","caller":"traceutil/trace.go:171","msg":"trace[862624198] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"343.20184ms","start":"2026-02-01T18:55:37.708559Z","end":"2026-02-01T18:55:38.051761Z","steps":["trace[862624198] 'process raft request' (duration: 132.15217ms)","trace[862624198] 'compare' (duration: 210.846715ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:38.051814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:37.708543Z","time spent":"343.25249ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:38.8323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.822562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:38.832356Z","caller":"traceutil/trace.go:171","msg":"trace[415237281] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:908; }","duration":"1.009424899s","start":"2026-02-01T18:55:37.822922Z","end":"2026-02-01T18:55:38.832347Z","steps":["trace[415237281] 'read index received' (duration: 17.836388ms)","trace[415237281] 'applied index is now lower than readState.Index' (duration: 991.587871ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:38.832404Z","caller":"traceutil/trace.go:171","msg":"trace[344288859] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"1.12370127s","start":"2026-02-01T18:55:37.708697Z","end":"2026-02-01T18:55:38.832399Z","steps":["trace[344288859] 'process raft request' (duration: 746.731025ms)","trace[344288859] 'compare' (duration: 376.710599ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:38.83243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:37.70869Z","time spent":"1.12372613s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:38.832455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.025262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:38.832476Z","caller":"traceutil/trace.go:171","msg":"trace[1752534012] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:876; }","duration":"659.083544ms","start":"2026-02-01T18:55:38.173386Z","end":"2026-02-01T18:55:38.83247Z","steps":["trace[1752534012] 'agreement among raft nodes before linearized reading' (duration: 659.045913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:38.832493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:38.173368Z","time spent":"659.119524ms","remote":"127.0.0.1:54696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:38.832585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009667704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:38.832597Z","caller":"traceutil/trace.go:171","msg":"trace[182556063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"1.009703095s","start":"2026-02-01T18:55:37.82289Z","end":"2026-02-01T18:55:38.832593Z","steps":["trace[182556063] 'agreement among raft nodes before linearized reading' (duration: 1.009683805s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:38.83261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:37.822877Z","time spent":"1.009730655s","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:55:41.676694Z","caller":"traceutil/trace.go:171","msg":"trace[1307892327] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"103.808693ms","start":"2026-02-01T18:55:41.572869Z","end":"2026-02-01T18:55:41.676677Z","steps":["trace[1307892327] 'process raft request' (duration: 103.68674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:41.939612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.732654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:41.93968Z","caller":"traceutil/trace.go:171","msg":"trace[1789018019] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"116.512698ms","start":"2026-02-01T18:55:41.823155Z","end":"2026-02-01T18:55:41.939668Z","steps":["trace[1789018019] 'read index received' (duration: 29.17µs)","trace[1789018019] 'applied index is now lower than readState.Index' (duration: 116.482928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:41.939727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.57157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:41.939738Z","caller":"traceutil/trace.go:171","msg":"trace[2002298909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"116.611131ms","start":"2026-02-01T18:55:41.823123Z","end":"2026-02-01T18:55:41.939734Z","steps":["trace[2002298909] 'agreement among raft nodes before linearized reading' (duration: 116.57217ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:41.939987Z","caller":"traceutil/trace.go:171","msg":"trace[353215668] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"223.946121ms","start":"2026-02-01T18:55:41.716034Z","end":"2026-02-01T18:55:41.93998Z","steps":["trace[353215668] 'process raft request' (duration: 22.794968ms)","trace[353215668] 'compare' (duration: 200.649132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:42.359701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.236437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-01T18:55:42.359763Z","caller":"traceutil/trace.go:171","msg":"trace[2089885975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"296.310779ms","start":"2026-02-01T18:55:42.063436Z","end":"2026-02-01T18:55:42.359746Z","steps":["trace[2089885975] 'range keys from in-memory index tree' (duration: 296.117904ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:47.007024Z","caller":"traceutil/trace.go:171","msg":"trace[592519336] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"115.712962ms","start":"2026-02-01T18:55:46.891283Z","end":"2026-02-01T18:55:47.006996Z","steps":["trace[592519336] 'process raft request' (duration: 115.598209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:49.509991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624274605042858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:50.010781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624274605042858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:50.279806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.365484351s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:55:50.280274Z","caller":"traceutil/trace.go:171","msg":"trace[1158866624] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"1.270571601s","start":"2026-02-01T18:55:49.009673Z","end":"2026-02-01T18:55:50.280244Z","steps":["trace[1158866624] 'read index received' (duration: 1.270310096s)","trace[1158866624] 'applied index is now lower than readState.Index' (duration: 260.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:50.280656Z","caller":"traceutil/trace.go:171","msg":"trace[2143615150] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"1.366397471s","start":"2026-02-01T18:55:48.914247Z","end":"2026-02-01T18:55:50.280644Z","steps":["trace[2143615150] 'process raft request' (duration: 1.365836969s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.280798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:48.914234Z","time spent":"1.366451812s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:50.281091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271418339s","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-01T18:55:50.281152Z","caller":"traceutil/trace.go:171","msg":"trace[2116181831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:904; }","duration":"1.271503262s","start":"2026-02-01T18:55:49.009639Z","end":"2026-02-01T18:55:50.281142Z","steps":["trace[2116181831] 'agreement among raft nodes before linearized reading' (duration: 1.271338128s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.281195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.009624Z","time spent":"1.271563763s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:50.281347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.129988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.281387Z","caller":"traceutil/trace.go:171","msg":"trace[1385274526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"460.178049ms","start":"2026-02-01T18:55:49.821186Z","end":"2026-02-01T18:55:50.281364Z","steps":["trace[1385274526] 'agreement among raft nodes before linearized reading' (duration: 460.137329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.281412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.821173Z","time spent":"460.23342ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:50.283483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.597021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:50.283612Z","caller":"traceutil/trace.go:171","msg":"trace[2102927997] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:904; }","duration":"166.745664ms","start":"2026-02-01T18:55:50.116844Z","end":"2026-02-01T18:55:50.283589Z","steps":["trace[2102927997] 'agreement among raft nodes before linearized reading' (duration: 166.56628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.283872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.465394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-01T18:55:50.283941Z","caller":"traceutil/trace.go:171","msg":"trace[1905433584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"236.552256ms","start":"2026-02-01T18:55:50.047373Z","end":"2026-02-01T18:55:50.283926Z","steps":["trace[1905433584] 'agreement among raft nodes before linearized reading' (duration: 236.417904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.284135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.922766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.284315Z","caller":"traceutil/trace.go:171","msg":"trace[1866436280] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:904; }","duration":"338.052088ms","start":"2026-02-01T18:55:49.946199Z","end":"2026-02-01T18:55:50.284251Z","steps":["trace[1866436280] 'agreement among raft nodes before linearized reading' (duration: 337.903475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.284384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.94616Z","time spent":"338.207171ms","remote":"127.0.0.1:54618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:50.284634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.254779ms","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-01T18:55:50.284698Z","caller":"traceutil/trace.go:171","msg":"trace[537136276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:904; }","duration":"459.329041ms","start":"2026-02-01T18:55:49.825356Z","end":"2026-02-01T18:55:50.284685Z","steps":["trace[537136276] 'agreement among raft nodes before linearized reading' (duration: 459.203948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.284751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:49.82535Z","time spent":"459.391292ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:50.82628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.989936ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-01T18:55:50.826332Z","caller":"traceutil/trace.go:171","msg":"trace[1473329918] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"522.249763ms","start":"2026-02-01T18:55:50.304072Z","end":"2026-02-01T18:55:50.826322Z","steps":["trace[1473329918] 'read index received' (duration: 162.227726ms)","trace[1473329918] 'applied index is now lower than readState.Index' (duration: 360.021227ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:50.826371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.303868Z","time spent":"522.501398ms","remote":"127.0.0.1:50806","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-01T18:55:50.826484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.406856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:50.826497Z","caller":"traceutil/trace.go:171","msg":"trace[664995803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"522.437506ms","start":"2026-02-01T18:55:50.304055Z","end":"2026-02-01T18:55:50.826493Z","steps":["trace[664995803] 'agreement among raft nodes before linearized reading' (duration: 522.405806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:50.82651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.304047Z","time spent":"522.461007ms","remote":"127.0.0.1:50784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:51.397134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.894765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:51.39727Z","caller":"traceutil/trace.go:171","msg":"trace[492755787] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"568.145501ms","start":"2026-02-01T18:55:50.829114Z","end":"2026-02-01T18:55:51.39726Z","steps":["trace[492755787] 'process raft request' (duration: 568.1229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:51.397302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.829101Z","time spent":"568.186331ms","remote":"127.0.0.1:50806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:51.397453Z","caller":"traceutil/trace.go:171","msg":"trace[1653260442] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"1.090163168s","start":"2026-02-01T18:55:50.307284Z","end":"2026-02-01T18:55:51.397448Z","steps":["trace[1653260442] 'process raft request' (duration: 907.911686ms)","trace[1653260442] 'compare' (duration: 181.742881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:51.397486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.307276Z","time spent":"1.090188708s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:51.39754Z","caller":"traceutil/trace.go:171","msg":"trace[1598246886] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"1.086950848s","start":"2026-02-01T18:55:50.310584Z","end":"2026-02-01T18:55:51.397535Z","steps":["trace[1598246886] 'process raft request' (duration: 1.08660125s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:51.397563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.310578Z","time spent":"1.086972038s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:51.397635Z","caller":"traceutil/trace.go:171","msg":"trace[1127686380] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"1.085629069s","start":"2026-02-01T18:55:50.312002Z","end":"2026-02-01T18:55:51.397631Z","steps":["trace[1127686380] 'process raft request' (duration: 1.08521527s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:51.39766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.311996Z","time spent":"1.085651039s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:51.397729Z","caller":"traceutil/trace.go:171","msg":"trace[884521723] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:941; }","duration":"571.378001ms","start":"2026-02-01T18:55:50.826345Z","end":"2026-02-01T18:55:51.397723Z","steps":["trace[884521723] 'read index received' (duration: 388.857663ms)","trace[884521723] 'applied index is now lower than readState.Index' (duration: 182.519788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:51.397838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.531475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:51.397852Z","caller":"traceutil/trace.go:171","msg":"trace[443047997] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:908; }","duration":"844.566486ms","start":"2026-02-01T18:55:50.553281Z","end":"2026-02-01T18:55:51.397847Z","steps":["trace[443047997] 'agreement among raft nodes before linearized reading' (duration: 844.539646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:51.397865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.553267Z","time spent":"844.595287ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:51.397965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.545623ms","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-01T18:55:51.397976Z","caller":"traceutil/trace.go:171","msg":"trace[535117985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"954.580273ms","start":"2026-02-01T18:55:50.443393Z","end":"2026-02-01T18:55:51.397973Z","steps":["trace[535117985] 'agreement among raft nodes before linearized reading' (duration: 954.527962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:51.397987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.443381Z","time spent":"954.604084ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:55:51.398044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.051071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:51.398056Z","caller":"traceutil/trace.go:171","msg":"trace[802861391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"552.078751ms","start":"2026-02-01T18:55:50.845974Z","end":"2026-02-01T18:55:51.398053Z","steps":["trace[802861391] 'agreement among raft nodes before linearized reading' (duration: 552.06136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:51.398066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:50.845967Z","time spent":"552.096971ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:55:53.571709Z","caller":"traceutil/trace.go:171","msg":"trace[1463369940] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"166.908398ms","start":"2026-02-01T18:55:53.404782Z","end":"2026-02-01T18:55:53.571691Z","steps":["trace[1463369940] 'process raft request' (duration: 166.787434ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:53.572428Z","caller":"traceutil/trace.go:171","msg":"trace[1766015477] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"153.164748ms","start":"2026-02-01T18:55:53.419238Z","end":"2026-02-01T18:55:53.572402Z","steps":["trace[1766015477] 'read index received' (duration: 152.852292ms)","trace[1766015477] 'applied index is now lower than readState.Index' (duration: 311.386µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:55:53.572539Z","caller":"traceutil/trace.go:171","msg":"trace[503613042] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"166.141941ms","start":"2026-02-01T18:55:53.406387Z","end":"2026-02-01T18:55:53.572529Z","steps":["trace[503613042] 'process raft request' (duration: 165.865265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:53.572621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.357392ms","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-01T18:55:53.572656Z","caller":"traceutil/trace.go:171","msg":"trace[1983376003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:914; }","duration":"153.432935ms","start":"2026-02-01T18:55:53.419213Z","end":"2026-02-01T18:55:53.572646Z","steps":["trace[1983376003] 'agreement among raft nodes before linearized reading' (duration: 153.273131ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:53.572807Z","caller":"traceutil/trace.go:171","msg":"trace[1024811037] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"166.354805ms","start":"2026-02-01T18:55:53.406443Z","end":"2026-02-01T18:55:53.572798Z","steps":["trace[1024811037] 'process raft request' (duration: 165.922006ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:54.670985Z","caller":"traceutil/trace.go:171","msg":"trace[1004178223] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"230.500573ms","start":"2026-02-01T18:55:54.44047Z","end":"2026-02-01T18:55:54.67097Z","steps":["trace[1004178223] 'read index received' (duration: 230.386401ms)","trace[1004178223] 'applied index is now lower than readState.Index' (duration: 113.622µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:54.671086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.595205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:54.671109Z","caller":"traceutil/trace.go:171","msg":"trace[1324877316] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:916; }","duration":"230.650976ms","start":"2026-02-01T18:55:54.440451Z","end":"2026-02-01T18:55:54.671102Z","steps":["trace[1324877316] 'agreement among raft nodes before linearized reading' (duration: 230.577044ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:54.671322Z","caller":"traceutil/trace.go:171","msg":"trace[2133802944] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"331.186858ms","start":"2026-02-01T18:55:54.340129Z","end":"2026-02-01T18:55:54.671316Z","steps":["trace[2133802944] 'process raft request' (duration: 330.765969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:54.671373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:54.340119Z","time spent":"331.222068ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:56.077279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624274605042895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:55:56.372752Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.038011262s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T18:55:56.909013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.116635ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-01T18:55:56.909177Z","caller":"traceutil/trace.go:171","msg":"trace[1109990257] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"809.644366ms","start":"2026-02-01T18:55:56.099521Z","end":"2026-02-01T18:55:56.909165Z","steps":["trace[1109990257] 'process raft request' (duration: 809.563005ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:55:56.909195Z","caller":"traceutil/trace.go:171","msg":"trace[1368094539] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"1.33293713s","start":"2026-02-01T18:55:55.576233Z","end":"2026-02-01T18:55:56.90917Z","steps":["trace[1368094539] 'read index received' (duration: 796.767866ms)","trace[1368094539] 'applied index is now lower than readState.Index' (duration: 536.165334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:56.9094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333148525s","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-01T18:55:56.909418Z","caller":"traceutil/trace.go:171","msg":"trace[365557186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"1.333211986s","start":"2026-02-01T18:55:55.576201Z","end":"2026-02-01T18:55:56.909413Z","steps":["trace[365557186] 'agreement among raft nodes before linearized reading' (duration: 1.333073963s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.909438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.576184Z","time spent":"1.333250397s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:55:56.909538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:56.099487Z","time spent":"810.002323ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:55:56.90966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332644904s","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-01T18:55:56.909676Z","caller":"traceutil/trace.go:171","msg":"trace[1458051291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"1.332679434s","start":"2026-02-01T18:55:55.576993Z","end":"2026-02-01T18:55:56.909672Z","steps":["trace[1458051291] 'agreement among raft nodes before linearized reading' (duration: 1.332637413s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.909689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.576984Z","time spent":"1.332702144s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:55:56.909766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.738666ms","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-01T18:55:56.909777Z","caller":"traceutil/trace.go:171","msg":"trace[99061498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"234.784567ms","start":"2026-02-01T18:55:56.674989Z","end":"2026-02-01T18:55:56.909774Z","steps":["trace[99061498] 'agreement among raft nodes before linearized reading' (duration: 234.751877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.909827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.162852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:56.909837Z","caller":"traceutil/trace.go:171","msg":"trace[1798977831] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:917; }","duration":"373.211733ms","start":"2026-02-01T18:55:56.536622Z","end":"2026-02-01T18:55:56.909834Z","steps":["trace[1798977831] 'agreement among raft nodes before linearized reading' (duration: 373.184692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.909848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:56.536601Z","time spent":"373.243704ms","remote":"127.0.0.1:51156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:56.909915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.398827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:55:56.909926Z","caller":"traceutil/trace.go:171","msg":"trace[1597345400] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:917; }","duration":"763.441138ms","start":"2026-02-01T18:55:56.146481Z","end":"2026-02-01T18:55:56.909922Z","steps":["trace[1597345400] 'agreement among raft nodes before linearized reading' (duration: 763.420198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.909937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:56.146465Z","time spent":"763.469529ms","remote":"127.0.0.1:51208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:55:56.909995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084314961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:55:56.910007Z","caller":"traceutil/trace.go:171","msg":"trace[1907226461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"1.084355702s","start":"2026-02-01T18:55:55.825646Z","end":"2026-02-01T18:55:56.910002Z","steps":["trace[1907226461] 'agreement among raft nodes before linearized reading' (duration: 1.084338612s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.910018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.825589Z","time spent":"1.084427074s","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:55:56.910097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205459861s","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-01T18:55:56.910108Z","caller":"traceutil/trace.go:171","msg":"trace[407621051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:917; }","duration":"1.205509512s","start":"2026-02-01T18:55:55.704595Z","end":"2026-02-01T18:55:56.910104Z","steps":["trace[407621051] 'agreement among raft nodes before linearized reading' (duration: 1.205473862s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.910118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.704571Z","time spent":"1.205544524s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:55:56.910168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326438868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:55:56.910179Z","caller":"traceutil/trace.go:171","msg":"trace[673355531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"1.326488069s","start":"2026-02-01T18:55:55.583687Z","end":"2026-02-01T18:55:56.910176Z","steps":["trace[673355531] 'agreement among raft nodes before linearized reading' (duration: 1.326458868s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:56.910189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:55.583677Z","time spent":"1.326508979s","remote":"127.0.0.1:51098","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-01T18:55:59.7359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.727037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:55:59.73629Z","caller":"traceutil/trace.go:171","msg":"trace[1183407726] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"741.567552ms","start":"2026-02-01T18:55:58.994709Z","end":"2026-02-01T18:55:59.736277Z","steps":["trace[1183407726] 'process raft request' (duration: 639.403516ms)","trace[1183407726] 'compare' (duration: 101.604974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:55:59.736346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:58.994706Z","time spent":"741.615763ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:59.736438Z","caller":"traceutil/trace.go:171","msg":"trace[925192327] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"740.918748ms","start":"2026-02-01T18:55:58.995513Z","end":"2026-02-01T18:55:59.736432Z","steps":["trace[925192327] 'process raft request' (duration: 740.448278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:59.736478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:58.995508Z","time spent":"740.949828ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:59.736577Z","caller":"traceutil/trace.go:171","msg":"trace[1760456364] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"741.00352ms","start":"2026-02-01T18:55:58.995568Z","end":"2026-02-01T18:55:59.736571Z","steps":["trace[1760456364] 'process raft request' (duration: 740.424477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:59.736617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:58.995563Z","time spent":"741.035ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:55:59.7367Z","caller":"traceutil/trace.go:171","msg":"trace[36786994] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"740.476848ms","start":"2026-02-01T18:55:58.996217Z","end":"2026-02-01T18:55:59.736694Z","steps":["trace[36786994] 'process raft request' (duration: 739.814524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:55:59.736737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:55:58.996212Z","time spent":"740.506459ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:00.585975Z","caller":"traceutil/trace.go:171","msg":"trace[870843203] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"289.412527ms","start":"2026-02-01T18:56:00.296541Z","end":"2026-02-01T18:56:00.585953Z","steps":["trace[870843203] 'read index received' (duration: 221.529578ms)","trace[870843203] 'applied index is now lower than readState.Index' (duration: 67.881969ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:00.586381Z","caller":"traceutil/trace.go:171","msg":"trace[1661886203] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"297.709239ms","start":"2026-02-01T18:56:00.288657Z","end":"2026-02-01T18:56:00.586366Z","steps":["trace[1661886203] 'process raft request' (duration: 229.476641ms)","trace[1661886203] 'compare' (duration: 67.369859ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:00.586744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.179955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:00.587013Z","caller":"traceutil/trace.go:171","msg":"trace[609663648] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:928; }","duration":"290.424039ms","start":"2026-02-01T18:56:00.296516Z","end":"2026-02-01T18:56:00.58694Z","steps":["trace[609663648] 'agreement among raft nodes before linearized reading' (duration: 290.153714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:00.587089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.946309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:56:00.587333Z","caller":"traceutil/trace.go:171","msg":"trace[1592815366] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:928; }","duration":"162.217666ms","start":"2026-02-01T18:56:00.425103Z","end":"2026-02-01T18:56:00.587321Z","steps":["trace[1592815366] 'agreement among raft nodes before linearized reading' (duration: 161.927349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:00.92826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.488036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:00.92838Z","caller":"traceutil/trace.go:171","msg":"trace[254898072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"102.64248ms","start":"2026-02-01T18:56:00.825715Z","end":"2026-02-01T18:56:00.928357Z","steps":["trace[254898072] 'range keys from in-memory index tree' (duration: 102.417945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:01.731059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.928602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:56:01.731126Z","caller":"traceutil/trace.go:171","msg":"trace[511566021] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:929; }","duration":"414.060666ms","start":"2026-02-01T18:56:01.31705Z","end":"2026-02-01T18:56:01.731111Z","steps":["trace[511566021] 'count revisions from in-memory index tree' (duration: 413.873142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:01.73116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:01.317038Z","time spent":"414.113617ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-01T18:56:01.922909Z","caller":"traceutil/trace.go:171","msg":"trace[280193140] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"170.791606ms","start":"2026-02-01T18:56:01.7521Z","end":"2026-02-01T18:56:01.922891Z","steps":["trace[280193140] 'process raft request' (duration: 170.695453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:02.289542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.131066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:02.289778Z","caller":"traceutil/trace.go:171","msg":"trace[1453869504] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"534.690948ms","start":"2026-02-01T18:56:01.755068Z","end":"2026-02-01T18:56:02.289759Z","steps":["trace[1453869504] 'process raft request' (duration: 185.253984ms)","trace[1453869504] 'compare' (duration: 349.017114ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:02.289823Z","caller":"traceutil/trace.go:171","msg":"trace[923977810] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:968; }","duration":"462.303766ms","start":"2026-02-01T18:56:01.827503Z","end":"2026-02-01T18:56:02.289806Z","steps":["trace[923977810] 'read index received' (duration: 95.234364ms)","trace[923977810] 'applied index is now lower than readState.Index' (duration: 367.068512ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:02.289863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:01.75506Z","time spent":"534.756299ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:02.289913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.390898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:02.28994Z","caller":"traceutil/trace.go:171","msg":"trace[101005577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"462.506181ms","start":"2026-02-01T18:56:01.827425Z","end":"2026-02-01T18:56:02.289931Z","steps":["trace[101005577] 'agreement among raft nodes before linearized reading' (duration: 462.429399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:02.289968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:01.82741Z","time spent":"462.552181ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:56:02.290043Z","caller":"traceutil/trace.go:171","msg":"trace[108060339] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"534.916833ms","start":"2026-02-01T18:56:01.75512Z","end":"2026-02-01T18:56:02.290037Z","steps":["trace[108060339] 'process raft request' (duration: 534.584126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:02.290081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:01.755115Z","time spent":"534.944394ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:02.290149Z","caller":"traceutil/trace.go:171","msg":"trace[1673835182] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"534.951173ms","start":"2026-02-01T18:56:01.755189Z","end":"2026-02-01T18:56:02.29014Z","steps":["trace[1673835182] 'process raft request' (duration: 534.582215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:02.290203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:01.755168Z","time spent":"535.005495ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:05.341416Z","caller":"traceutil/trace.go:171","msg":"trace[487860111] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"187.614567ms","start":"2026-02-01T18:56:05.153767Z","end":"2026-02-01T18:56:05.341382Z","steps":["trace[487860111] 'process raft request' (duration: 187.400232ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:16.260425Z","caller":"traceutil/trace.go:171","msg":"trace[1744083294] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"270.185912ms","start":"2026-02-01T18:56:15.990221Z","end":"2026-02-01T18:56:16.260407Z","steps":["trace[1744083294] 'read index received' (duration: 270.029748ms)","trace[1744083294] 'applied index is now lower than readState.Index' (duration: 155.314µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:56:16.2605Z","caller":"traceutil/trace.go:171","msg":"trace[379404723] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"332.579413ms","start":"2026-02-01T18:56:15.927914Z","end":"2026-02-01T18:56:16.260493Z","steps":["trace[379404723] 'process raft request' (duration: 332.386449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:16.260603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:15.927883Z","time spent":"332.635165ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:16.260896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.684548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-02-01T18:56:16.261061Z","caller":"traceutil/trace.go:171","msg":"trace[1231911871] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1033; }","duration":"152.904982ms","start":"2026-02-01T18:56:16.108141Z","end":"2026-02-01T18:56:16.261046Z","steps":["trace[1231911871] 'agreement among raft nodes before linearized reading' (duration: 152.609636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:16.260918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.694152ms","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-01T18:56:16.261426Z","caller":"traceutil/trace.go:171","msg":"trace[686292899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1033; }","duration":"271.227214ms","start":"2026-02-01T18:56:15.990191Z","end":"2026-02-01T18:56:16.261418Z","steps":["trace[686292899] 'agreement among raft nodes before linearized reading' (duration: 270.681623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:16.849279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624274605043136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:56:17.349919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620624274605043136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T18:56:17.374086Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.108947096s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T18:56:17.506646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.235969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:17.507385Z","caller":"traceutil/trace.go:171","msg":"trace[832629248] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"1.242285018s","start":"2026-02-01T18:56:16.265073Z","end":"2026-02-01T18:56:17.507358Z","steps":["trace[832629248] 'process raft request' (duration: 1.109263962s)","trace[832629248] 'compare' (duration: 131.939902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:17.507715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:16.265056Z","time spent":"1.242392281s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:17.767991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.021784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:56:17.768409Z","caller":"traceutil/trace.go:171","msg":"trace[1923864379] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1075; }","duration":"1.419563417s","start":"2026-02-01T18:56:16.348816Z","end":"2026-02-01T18:56:17.768379Z","steps":["trace[1923864379] 'read index received' (duration: 1.025529741s)","trace[1923864379] 'applied index is now lower than readState.Index' (duration: 394.032636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:17.76892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413149424s","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-01T18:56:17.768964Z","caller":"traceutil/trace.go:171","msg":"trace[1677354238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"1.413207516s","start":"2026-02-01T18:56:16.355746Z","end":"2026-02-01T18:56:17.768953Z","steps":["trace[1677354238] 'agreement among raft nodes before linearized reading' (duration: 1.413074903s)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:56:17.769274Z","caller":"traceutil/trace.go:171","msg":"trace[1758683784] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"1.50382665s","start":"2026-02-01T18:56:16.265424Z","end":"2026-02-01T18:56:17.769251Z","steps":["trace[1758683784] 'process raft request' (duration: 1.400473297s)","trace[1758683784] 'compare' (duration: 101.921372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:56:17.76944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:16.265392Z","time spent":"1.503965182s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:56:17.769771Z","caller":"traceutil/trace.go:171","msg":"trace[798460730] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"444.142814ms","start":"2026-02-01T18:56:17.325612Z","end":"2026-02-01T18:56:17.769755Z","steps":["trace[798460730] 'process raft request' (duration: 442.459408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:17.77121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:17.325579Z","time spent":"445.571887ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:56:17.769535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:16.355739Z","time spent":"1.413449081s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:56:17.770151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.242043ms","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-01T18:56:17.771841Z","caller":"traceutil/trace.go:171","msg":"trace[1053271780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1036; }","duration":"341.958799ms","start":"2026-02-01T18:56:17.429867Z","end":"2026-02-01T18:56:17.771826Z","steps":["trace[1053271780] 'agreement among raft nodes before linearized reading' (duration: 340.194641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:17.77198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:17.429852Z","time spent":"342.111813ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:56:17.769072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420220081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:56:17.772691Z","caller":"traceutil/trace.go:171","msg":"trace[1510786624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"1.423891652s","start":"2026-02-01T18:56:16.348786Z","end":"2026-02-01T18:56:17.772677Z","steps":["trace[1510786624] 'agreement among raft nodes before linearized reading' (duration: 1.419950924s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:17.77283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:16.348772Z","time spent":"1.424042496s","remote":"127.0.0.1:51098","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-01T18:56:17.770196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.187476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:56:17.773212Z","caller":"traceutil/trace.go:171","msg":"trace[335579901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"948.244223ms","start":"2026-02-01T18:56:16.824955Z","end":"2026-02-01T18:56:17.773199Z","steps":["trace[335579901] 'agreement among raft nodes before linearized reading' (duration: 945.224417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:17.773351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:16.824937Z","time spent":"948.399996ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:56:17.770261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011262817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-01T18:56:17.773775Z","caller":"traceutil/trace.go:171","msg":"trace[789266917] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1036; }","duration":"1.014787345s","start":"2026-02-01T18:56:16.758971Z","end":"2026-02-01T18:56:17.773758Z","steps":["trace[789266917] 'agreement among raft nodes before linearized reading' (duration: 1.011241057s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:17.774161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:16.758936Z","time spent":"1.015074692s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-01T18:56:17.770327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413543274s","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-01T18:56:17.774593Z","caller":"traceutil/trace.go:171","msg":"trace[405913636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"1.417816458s","start":"2026-02-01T18:56:16.356759Z","end":"2026-02-01T18:56:17.774576Z","steps":["trace[405913636] 'agreement among raft nodes before linearized reading' (duration: 1.413518484s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:56:17.774957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:56:16.35675Z","time spent":"1.418188886s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:58:10.432311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.877407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.65\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T18:58:10.432381Z","caller":"traceutil/trace.go:171","msg":"trace[2078705333] range","detail":"{range_begin:/registry/masterleases/199.204.45.65; range_end:; response_count:1; response_revision:1497; }","duration":"227.954748ms","start":"2026-02-01T18:58:10.204413Z","end":"2026-02-01T18:58:10.432367Z","steps":["trace[2078705333] 'range keys from in-memory index tree' (duration: 227.477448ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:31.569515Z","caller":"traceutil/trace.go:171","msg":"trace[780426844] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"120.900666ms","start":"2026-02-01T18:59:31.448593Z","end":"2026-02-01T18:59:31.569494Z","steps":["trace[780426844] 'process raft request' (duration: 120.840035ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:31.569988Z","caller":"traceutil/trace.go:171","msg":"trace[1202774063] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"121.667552ms","start":"2026-02-01T18:59:31.448282Z","end":"2026-02-01T18:59:31.569949Z","steps":["trace[1202774063] 'process raft request' (duration: 120.487827ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:35.534322Z","caller":"traceutil/trace.go:171","msg":"trace[1182208590] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"143.814607ms","start":"2026-02-01T18:59:35.390481Z","end":"2026-02-01T18:59:35.534296Z","steps":["trace[1182208590] 'process raft request' (duration: 106.852358ms)","trace[1182208590] 'compare' (duration: 36.211163ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:47.037176Z","caller":"traceutil/trace.go:171","msg":"trace[1468356141] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"157.826714ms","start":"2026-02-01T18:59:46.87933Z","end":"2026-02-01T18:59:47.037157Z","steps":["trace[1468356141] 'process raft request' (duration: 157.680161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:48.075286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.603117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:59:48.07544Z","caller":"traceutil/trace.go:171","msg":"trace[1602614789] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2002; }","duration":"252.936265ms","start":"2026-02-01T18:59:47.822487Z","end":"2026-02-01T18:59:48.075423Z","steps":["trace[1602614789] 'read index received' (duration: 41.836736ms)","trace[1602614789] 'applied index is now lower than readState.Index' (duration: 211.098299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:59:48.075514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.023767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:59:48.075537Z","caller":"traceutil/trace.go:171","msg":"trace[1787651261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1920; }","duration":"253.070249ms","start":"2026-02-01T18:59:47.822459Z","end":"2026-02-01T18:59:48.075529Z","steps":["trace[1787651261] 'agreement among raft nodes before linearized reading' (duration: 253.011477ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:48.075632Z","caller":"traceutil/trace.go:171","msg":"trace[653718307] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"269.097499ms","start":"2026-02-01T18:59:47.806502Z","end":"2026-02-01T18:59:48.075599Z","steps":["trace[653718307] 'process raft request' (duration: 128.097104ms)","trace[653718307] 'compare' (duration: 140.485864ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:48.075734Z","caller":"traceutil/trace.go:171","msg":"trace[499347997] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"267.816782ms","start":"2026-02-01T18:59:47.807907Z","end":"2026-02-01T18:59:48.075724Z","steps":["trace[499347997] 'process raft request' (duration: 267.465164ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:48.475204Z","caller":"traceutil/trace.go:171","msg":"trace[324385309] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"180.099042ms","start":"2026-02-01T18:59:48.295076Z","end":"2026-02-01T18:59:48.475175Z","steps":["trace[324385309] 'process raft request' (duration: 179.953199ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:49.597731Z","caller":"traceutil/trace.go:171","msg":"trace[1206293125] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"316.082368ms","start":"2026-02-01T18:59:49.281637Z","end":"2026-02-01T18:59:49.597719Z","steps":["trace[1206293125] 'process raft request' (duration: 315.822512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:49.597812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:59:49.281585Z","time spent":"316.18323ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:59:49.841016Z","caller":"traceutil/trace.go:171","msg":"trace[351829153] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"239.305747ms","start":"2026-02-01T18:59:49.601628Z","end":"2026-02-01T18:59:49.840933Z","steps":["trace[351829153] 'process raft request' (duration: 234.611684ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:49.994316Z","caller":"traceutil/trace.go:171","msg":"trace[1661752345] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"123.208047ms","start":"2026-02-01T18:59:49.87109Z","end":"2026-02-01T18:59:49.994298Z","steps":["trace[1661752345] 'process raft request' (duration: 123.099814ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:50.197189Z","caller":"traceutil/trace.go:171","msg":"trace[26132114] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"111.198143ms","start":"2026-02-01T18:59:50.085966Z","end":"2026-02-01T18:59:50.197164Z","steps":["trace[26132114] 'process raft request' (duration: 110.943578ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:50.197927Z","caller":"traceutil/trace.go:171","msg":"trace[938541895] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"112.074883ms","start":"2026-02-01T18:59:50.085831Z","end":"2026-02-01T18:59:50.197906Z","steps":["trace[938541895] 'process raft request' (duration: 59.270087ms)","trace[938541895] 'compare' (duration: 51.577689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:59:50.474821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.754847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-01T18:59:50.474917Z","caller":"traceutil/trace.go:171","msg":"trace[1795885646] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1930; }","duration":"154.891279ms","start":"2026-02-01T18:59:50.320003Z","end":"2026-02-01T18:59:50.474894Z","steps":["trace[1795885646] 'range keys from in-memory index tree' (duration: 154.535892ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:50.59096Z","caller":"traceutil/trace.go:171","msg":"trace[1613265295] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"110.750655ms","start":"2026-02-01T18:59:50.480189Z","end":"2026-02-01T18:59:50.59094Z","steps":["trace[1613265295] 'process raft request' (duration: 110.643682ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:51.704316Z","caller":"traceutil/trace.go:171","msg":"trace[795705027] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"100.239693ms","start":"2026-02-01T18:59:51.604061Z","end":"2026-02-01T18:59:51.704301Z","steps":["trace[795705027] 'process raft request' (duration: 100.162461ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:59.11902Z","caller":"traceutil/trace.go:171","msg":"trace[785242134] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"231.748692ms","start":"2026-02-01T18:59:58.887253Z","end":"2026-02-01T18:59:59.119002Z","steps":["trace[785242134] 'process raft request' (duration: 231.720392ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:59.119651Z","caller":"traceutil/trace.go:171","msg":"trace[1332210547] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"236.941835ms","start":"2026-02-01T18:59:58.882701Z","end":"2026-02-01T18:59:59.119643Z","steps":["trace[1332210547] 'process raft request' (duration: 222.808376ms)","trace[1332210547] 'compare' (duration: 13.23651ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:12.11165Z","caller":"traceutil/trace.go:171","msg":"trace[1761274962] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"125.065388ms","start":"2026-02-01T19:00:11.986563Z","end":"2026-02-01T19:00:12.111629Z","steps":["trace[1761274962] 'process raft request' (duration: 88.284453ms)","trace[1761274962] 'compare' (duration: 36.58716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:32.31833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.03404ms","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-01T19:00:32.318407Z","caller":"traceutil/trace.go:171","msg":"trace[1391924622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2111; }","duration":"102.142462ms","start":"2026-02-01T19:00:32.216246Z","end":"2026-02-01T19:00:32.318388Z","steps":["trace[1391924622] 'range keys from in-memory index tree' (duration: 101.923037ms)"],"step_count":1}