{"level":"warn","ts":"2026-02-03T10:27:30.211912Z","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-03T10:27:30.21241Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.215:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.215:2380","--initial-cluster=instance=https://199.19.213.215:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.215:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.215: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-03T10:27:30.212533Z","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-03T10:27:30.212577Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.215:2380"]} {"level":"info","ts":"2026-02-03T10:27:30.212624Z","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-03T10:27:30.214027Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"]} {"level":"info","ts":"2026-02-03T10:27:30.214585Z","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.19.213.215:2380"],"listen-peer-urls":["https://199.19.213.215:2380"],"advertise-client-urls":["https://199.19.213.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.215: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-03T10:27:30.220461Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.297949ms"} {"level":"info","ts":"2026-02-03T10:27:30.234651Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e6e5712872899056","cluster-id":"5d812b997db5c8f"} {"level":"info","ts":"2026-02-03T10:27:30.234873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T10:27:30.235379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became follower at term 0"} {"level":"info","ts":"2026-02-03T10:27:30.235402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e6e5712872899056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T10:27:30.235457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became follower at term 1"} {"level":"info","ts":"2026-02-03T10:27:30.23557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=(16637828816924414038)"} {"level":"warn","ts":"2026-02-03T10:27:30.259504Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T10:27:30.272674Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T10:27:30.276529Z","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-03T10:27:30.288353Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e6e5712872899056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T10:27:30.290936Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e6e5712872899056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T10:27:30.291969Z","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-03T10:27:30.292047Z","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-03T10:27:30.292109Z","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-03T10:27:30.292377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=(16637828816924414038)"} {"level":"info","ts":"2026-02-03T10:27:30.292517Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5d812b997db5c8f","local-member-id":"e6e5712872899056","added-peer-id":"e6e5712872899056","added-peer-peer-urls":["https://199.19.213.215:2380"]} {"level":"info","ts":"2026-02-03T10:27:30.298828Z","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-03T10:27:30.2997Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e6e5712872899056","initial-advertise-peer-urls":["https://199.19.213.215:2380"],"listen-peer-urls":["https://199.19.213.215:2380"],"advertise-client-urls":["https://199.19.213.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T10:27:30.299417Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.215:2380"} {"level":"info","ts":"2026-02-03T10:27:30.300955Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.215:2380"} {"level":"info","ts":"2026-02-03T10:27:30.300481Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T10:27:31.037948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T10:27:31.038001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T10:27:31.038022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 received MsgPreVoteResp from e6e5712872899056 at term 1"} {"level":"info","ts":"2026-02-03T10:27:31.038033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became candidate at term 2"} {"level":"info","ts":"2026-02-03T10:27:31.038044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 received MsgVoteResp from e6e5712872899056 at term 2"} {"level":"info","ts":"2026-02-03T10:27:31.038052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became leader at term 2"} {"level":"info","ts":"2026-02-03T10:27:31.038059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e6e5712872899056 elected leader e6e5712872899056 at term 2"} {"level":"info","ts":"2026-02-03T10:27:31.046102Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e6e5712872899056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.215:2379]}","request-path":"/0/members/e6e5712872899056/attributes","cluster-id":"5d812b997db5c8f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T10:27:31.046123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:27:31.046378Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:27:31.047024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T10:27:31.047144Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T10:27:31.047152Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T10:27:31.051074Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T10:27:31.053812Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.215:2379"} {"level":"info","ts":"2026-02-03T10:27:31.172411Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5d812b997db5c8f","local-member-id":"e6e5712872899056","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:27:31.172479Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:27:31.172502Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T10:27:33.717023Z","caller":"traceutil/trace.go:171","msg":"trace[1130503522] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"124.650422ms","start":"2026-02-03T10:27:33.592347Z","end":"2026-02-03T10:27:33.716997Z","steps":["trace[1130503522] 'read index received' (duration: 78.906018ms)","trace[1130503522] 'applied index is now lower than readState.Index' (duration: 45.743434ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:27:33.717346Z","caller":"traceutil/trace.go:171","msg":"trace[1058902179] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"126.733771ms","start":"2026-02-03T10:27:33.590597Z","end":"2026-02-03T10:27:33.717331Z","steps":["trace[1058902179] 'process raft request' (duration: 80.710931ms)","trace[1058902179] 'compare' (duration: 45.557051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:33.717903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.542549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-03T10:27:33.718138Z","caller":"traceutil/trace.go:171","msg":"trace[176688012] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:118; }","duration":"125.833964ms","start":"2026-02-03T10:27:33.592289Z","end":"2026-02-03T10:27:33.718123Z","steps":["trace[176688012] 'agreement among raft nodes before linearized reading' (duration: 125.516939ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:40.547807Z","caller":"traceutil/trace.go:171","msg":"trace[1348556407] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"235.411848ms","start":"2026-02-03T10:27:40.312374Z","end":"2026-02-03T10:27:40.547786Z","steps":["trace[1348556407] 'process raft request' (duration: 235.100572ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:40.59018Z","caller":"traceutil/trace.go:171","msg":"trace[1167803664] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"277.572767ms","start":"2026-02-03T10:27:40.312583Z","end":"2026-02-03T10:27:40.590156Z","steps":["trace[1167803664] 'process raft request' (duration: 277.352833ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:40.590583Z","caller":"traceutil/trace.go:171","msg":"trace[1426056000] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"276.426977ms","start":"2026-02-03T10:27:40.314146Z","end":"2026-02-03T10:27:40.590573Z","steps":["trace[1426056000] 'process raft request' (duration: 275.922207ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:40.590936Z","caller":"traceutil/trace.go:171","msg":"trace[1976655717] transaction","detail":"{read_only:false; number_of_response:1; response_revision:362; }","duration":"276.596179ms","start":"2026-02-03T10:27:40.314314Z","end":"2026-02-03T10:27:40.59091Z","steps":["trace[1976655717] 'process raft request' (duration: 275.796134ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:40.590298Z","caller":"traceutil/trace.go:171","msg":"trace[1114600706] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"276.254872ms","start":"2026-02-03T10:27:40.314028Z","end":"2026-02-03T10:27:40.590283Z","steps":["trace[1114600706] 'read index received' (duration: 233.795718ms)","trace[1114600706] 'applied index is now lower than readState.Index' (duration: 42.458264ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:40.590409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.359575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-03T10:27:40.593754Z","caller":"traceutil/trace.go:171","msg":"trace[1352140980] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:362; }","duration":"279.726997ms","start":"2026-02-03T10:27:40.314009Z","end":"2026-02-03T10:27:40.593735Z","steps":["trace[1352140980] 'agreement among raft nodes before linearized reading' (duration: 276.320953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:40.600866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.642032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T10:27:40.601037Z","caller":"traceutil/trace.go:171","msg":"trace[1931506861] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:362; }","duration":"137.844276ms","start":"2026-02-03T10:27:40.463167Z","end":"2026-02-03T10:27:40.601011Z","steps":["trace[1931506861] 'agreement among raft nodes before linearized reading' (duration: 131.04705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:40.601354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.396468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-03T10:27:40.601443Z","caller":"traceutil/trace.go:171","msg":"trace[1043959685] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:362; }","duration":"235.517199ms","start":"2026-02-03T10:27:40.365918Z","end":"2026-02-03T10:27:40.601435Z","steps":["trace[1043959685] 'agreement among raft nodes before linearized reading' (duration: 228.180984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:27:40.601635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.712346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-03T10:27:40.601764Z","caller":"traceutil/trace.go:171","msg":"trace[2010382280] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:362; }","duration":"188.892499ms","start":"2026-02-03T10:27:40.41284Z","end":"2026-02-03T10:27:40.601733Z","steps":["trace[2010382280] 'agreement among raft nodes before linearized reading' (duration: 181.361969ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:57.032721Z","caller":"traceutil/trace.go:171","msg":"trace[485974766] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"161.934222ms","start":"2026-02-03T10:27:56.870781Z","end":"2026-02-03T10:27:57.032715Z","steps":["trace[485974766] 'process raft request' (duration: 161.679837ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:57.032559Z","caller":"traceutil/trace.go:171","msg":"trace[1248024352] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"160.815221ms","start":"2026-02-03T10:27:56.871729Z","end":"2026-02-03T10:27:57.032544Z","steps":["trace[1248024352] 'read index received' (duration: 160.697448ms)","trace[1248024352] 'applied index is now lower than readState.Index' (duration: 117.363µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:27:57.03291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.170127ms","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-03T10:27:57.032944Z","caller":"traceutil/trace.go:171","msg":"trace[1950195528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"161.226837ms","start":"2026-02-03T10:27:56.871709Z","end":"2026-02-03T10:27:57.032936Z","steps":["trace[1950195528] 'agreement among raft nodes before linearized reading' (duration: 161.174547ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:27:57.365584Z","caller":"traceutil/trace.go:171","msg":"trace[276930438] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"151.916896ms","start":"2026-02-03T10:27:57.213646Z","end":"2026-02-03T10:27:57.365563Z","steps":["trace[276930438] 'process raft request' (duration: 151.788304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:03.768647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.4093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:03.76871Z","caller":"traceutil/trace.go:171","msg":"trace[1171207151] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"621.303315ms","start":"2026-02-03T10:28:03.147396Z","end":"2026-02-03T10:28:03.768699Z","steps":["trace[1171207151] 'process raft request' (duration: 177.524049ms)","trace[1171207151] 'compare' (duration: 443.340169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:03.768744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:03.147382Z","time spent":"621.346866ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:03.907113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672063781289830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:04.408146Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672063781289830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:04.90854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672063781289830,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:04.935349Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.610486325s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:28:04.973896Z","caller":"traceutil/trace.go:171","msg":"trace[1764349243] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"1.56741634s","start":"2026-02-03T10:28:03.406426Z","end":"2026-02-03T10:28:04.973843Z","steps":["trace[1764349243] 'read index received' (duration: 1.529112462s)","trace[1764349243] 'applied index is now lower than readState.Index' (duration: 38.302018ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:04.974098Z","caller":"traceutil/trace.go:171","msg":"trace[1881050320] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.758622681s","start":"2026-02-03T10:28:03.215453Z","end":"2026-02-03T10:28:04.974076Z","steps":["trace[1881050320] 'process raft request' (duration: 1.720148181s)","trace[1881050320] 'compare' (duration: 37.508202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:04.97416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532637928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T10:28:04.974214Z","caller":"traceutil/trace.go:171","msg":"trace[825013879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"1.53272866s","start":"2026-02-03T10:28:03.441467Z","end":"2026-02-03T10:28:04.974196Z","steps":["trace[825013879] 'agreement among raft nodes before linearized reading' (duration: 1.532641918s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.974217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:03.215437Z","time spent":"1.758705263s","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:04.974258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:03.441456Z","time spent":"1.53279262s","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:28:04.974466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.568037271s","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-03T10:28:04.974493Z","caller":"traceutil/trace.go:171","msg":"trace[1528121311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"1.568084652s","start":"2026-02-03T10:28:03.406399Z","end":"2026-02-03T10:28:04.974484Z","steps":["trace[1528121311] 'agreement among raft nodes before linearized reading' (duration: 1.56798886s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.974519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:03.406386Z","time spent":"1.568127543s","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:28:04.974618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350942072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:04.974645Z","caller":"traceutil/trace.go:171","msg":"trace[1685175493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"1.351009942s","start":"2026-02-03T10:28:03.623627Z","end":"2026-02-03T10:28:04.974637Z","steps":["trace[1685175493] 'agreement among raft nodes before linearized reading' (duration: 1.350971162s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:04.974671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:03.623613Z","time spent":"1.351052854s","remote":"127.0.0.1:40626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T10:28:05.251446Z","caller":"traceutil/trace.go:171","msg":"trace[1891537017] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:538; }","duration":"265.25096ms","start":"2026-02-03T10:28:04.986181Z","end":"2026-02-03T10:28:05.251432Z","steps":["trace[1891537017] 'read index received' (duration: 244.735251ms)","trace[1891537017] 'applied index is now lower than readState.Index' (duration: 20.515129ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:05.251486Z","caller":"traceutil/trace.go:171","msg":"trace[252873709] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"269.999687ms","start":"2026-02-03T10:28:04.981462Z","end":"2026-02-03T10:28:05.251462Z","steps":["trace[252873709] 'process raft request' (duration: 249.444427ms)","trace[252873709] 'compare' (duration: 20.412067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:05.251575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.378423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:05.251595Z","caller":"traceutil/trace.go:171","msg":"trace[693222139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"265.423813ms","start":"2026-02-03T10:28:04.986165Z","end":"2026-02-03T10:28:05.251588Z","steps":["trace[693222139] 'agreement among raft nodes before linearized reading' (duration: 265.365902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:05.251777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.752847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.215\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T10:28:05.251522Z","caller":"traceutil/trace.go:171","msg":"trace[984242674] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"269.973697ms","start":"2026-02-03T10:28:04.981543Z","end":"2026-02-03T10:28:05.251517Z","steps":["trace[984242674] 'process raft request' (duration: 269.867485ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:05.251791Z","caller":"traceutil/trace.go:171","msg":"trace[1755330572] range","detail":"{range_begin:/registry/masterleases/199.19.213.215; range_end:; response_count:1; response_revision:524; }","duration":"181.767248ms","start":"2026-02-03T10:28:05.07002Z","end":"2026-02-03T10:28:05.251787Z","steps":["trace[1755330572] 'agreement among raft nodes before linearized reading' (duration: 181.736527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:05.251887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.350483ms","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-03T10:28:05.2519Z","caller":"traceutil/trace.go:171","msg":"trace[1684382999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"133.386804ms","start":"2026-02-03T10:28:05.118509Z","end":"2026-02-03T10:28:05.251896Z","steps":["trace[1684382999] 'agreement among raft nodes before linearized reading' (duration: 133.364473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:10.453561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672063781289875,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:10.954692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672063781289875,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:11.4559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672063781289875,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:11.571616Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.738748024s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:28:11.571954Z","caller":"traceutil/trace.go:171","msg":"trace[1679594078] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.739196432s","start":"2026-02-03T10:28:09.832736Z","end":"2026-02-03T10:28:11.571932Z","steps":["trace[1679594078] 'process raft request' (duration: 1.73907061s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:11.572111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:09.83272Z","time spent":"1.739301145s","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:11.584939Z","caller":"traceutil/trace.go:171","msg":"trace[1382128736] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"1.632003153s","start":"2026-02-03T10:28:09.952914Z","end":"2026-02-03T10:28:11.584918Z","steps":["trace[1382128736] 'read index received' (duration: 1.619023893s)","trace[1382128736] 'applied index is now lower than readState.Index' (duration: 12.97802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:11.585141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632207377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rk9kq\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-03T10:28:11.585163Z","caller":"traceutil/trace.go:171","msg":"trace[1464791555] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rk9kq; range_end:; response_count:1; response_revision:539; }","duration":"1.632281168s","start":"2026-02-03T10:28:09.952875Z","end":"2026-02-03T10:28:11.585156Z","steps":["trace[1464791555] 'agreement among raft nodes before linearized reading' (duration: 1.632157115s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:11.585188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:09.952856Z","time spent":"1.632326849s","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-rk9kq\" "} {"level":"warn","ts":"2026-02-03T10:28:11.585381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.061857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:11.585399Z","caller":"traceutil/trace.go:171","msg":"trace[1568798297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"964.105867ms","start":"2026-02-03T10:28:10.621288Z","end":"2026-02-03T10:28:11.585393Z","steps":["trace[1568798297] 'agreement among raft nodes before linearized reading' (duration: 964.076937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:11.585416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:10.621273Z","time spent":"964.139659ms","remote":"127.0.0.1:40626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:28:11.585553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.954182ms","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-03T10:28:11.585571Z","caller":"traceutil/trace.go:171","msg":"trace[986832592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"207.998292ms","start":"2026-02-03T10:28:11.377567Z","end":"2026-02-03T10:28:11.585566Z","steps":["trace[986832592] 'agreement among raft nodes before linearized reading' (duration: 207.957822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:11.585676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.04929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T10:28:11.585692Z","caller":"traceutil/trace.go:171","msg":"trace[1923912963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"262.08774ms","start":"2026-02-03T10:28:11.3236Z","end":"2026-02-03T10:28:11.585687Z","steps":["trace[1923912963] 'agreement among raft nodes before linearized reading' (duration: 262.05722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:11.585771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.845817ms","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-03T10:28:11.585788Z","caller":"traceutil/trace.go:171","msg":"trace[893310529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"287.897478ms","start":"2026-02-03T10:28:11.297884Z","end":"2026-02-03T10:28:11.585782Z","steps":["trace[893310529] 'agreement among raft nodes before linearized reading' (duration: 287.870758ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:14.411859Z","caller":"traceutil/trace.go:171","msg":"trace[1181757178] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"151.253154ms","start":"2026-02-03T10:28:14.260561Z","end":"2026-02-03T10:28:14.411815Z","steps":["trace[1181757178] 'process raft request' (duration: 150.964118ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:14.536358Z","caller":"traceutil/trace.go:171","msg":"trace[1324677961] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"113.604237ms","start":"2026-02-03T10:28:14.422733Z","end":"2026-02-03T10:28:14.536337Z","steps":["trace[1324677961] 'process raft request' (duration: 107.357353ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:14.57439Z","caller":"traceutil/trace.go:171","msg":"trace[37166789] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"113.452375ms","start":"2026-02-03T10:28:14.460918Z","end":"2026-02-03T10:28:14.574371Z","steps":["trace[37166789] 'process raft request' (duration: 113.355873ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:14.798231Z","caller":"traceutil/trace.go:171","msg":"trace[323891766] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"124.373866ms","start":"2026-02-03T10:28:14.673836Z","end":"2026-02-03T10:28:14.79821Z","steps":["trace[323891766] 'process raft request' (duration: 109.773057ms)","trace[323891766] 'compare' (duration: 14.507538ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:15.045453Z","caller":"traceutil/trace.go:171","msg":"trace[1010447133] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"164.889066ms","start":"2026-02-03T10:28:14.880553Z","end":"2026-02-03T10:28:15.045442Z","steps":["trace[1010447133] 'process raft request' (duration: 98.867447ms)","trace[1010447133] 'compare' (duration: 65.798035ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:16.730971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.306872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:16.731019Z","caller":"traceutil/trace.go:171","msg":"trace[884547546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"107.411434ms","start":"2026-02-03T10:28:16.623595Z","end":"2026-02-03T10:28:16.731007Z","steps":["trace[884547546] 'range keys from in-memory index tree' (duration: 107.254732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:16.731078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.745524ms","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-03T10:28:16.731129Z","caller":"traceutil/trace.go:171","msg":"trace[1847912345] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:611; }","duration":"274.823916ms","start":"2026-02-03T10:28:16.456287Z","end":"2026-02-03T10:28:16.731111Z","steps":["trace[1847912345] 'range keys from in-memory index tree' (duration: 274.645453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.12017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672063781290123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T10:28:24.446684Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.296743321s","expected-duration":"1s"} {"level":"info","ts":"2026-02-03T10:28:24.44703Z","caller":"traceutil/trace.go:171","msg":"trace[1855844748] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"827.637286ms","start":"2026-02-03T10:28:23.619375Z","end":"2026-02-03T10:28:24.447012Z","steps":["trace[1855844748] 'read index received' (duration: 827.425392ms)","trace[1855844748] 'applied index is now lower than readState.Index' (duration: 211.154µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:24.447125Z","caller":"traceutil/trace.go:171","msg":"trace[725879668] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.297281411s","start":"2026-02-03T10:28:23.149837Z","end":"2026-02-03T10:28:24.447119Z","steps":["trace[725879668] 'process raft request' (duration: 1.297054797s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.447202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:23.149824Z","time spent":"1.297320871s","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:24.447396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.350293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-03T10:28:24.447436Z","caller":"traceutil/trace.go:171","msg":"trace[921736599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"787.410903ms","start":"2026-02-03T10:28:23.660002Z","end":"2026-02-03T10:28:24.447413Z","steps":["trace[921736599] 'agreement among raft nodes before linearized reading' (duration: 787.307451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.447458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:23.659987Z","time spent":"787.466744ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T10:28:24.447503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.095056ms","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-03T10:28:24.447528Z","caller":"traceutil/trace.go:171","msg":"trace[1716287548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"599.128076ms","start":"2026-02-03T10:28:23.848393Z","end":"2026-02-03T10:28:24.447521Z","steps":["trace[1716287548] 'agreement among raft nodes before linearized reading' (duration: 599.038764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.447547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:23.848389Z","time spent":"599.154826ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:24.447593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.96638ms","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-03T10:28:24.447613Z","caller":"traceutil/trace.go:171","msg":"trace[1933328737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"600.014782ms","start":"2026-02-03T10:28:23.847592Z","end":"2026-02-03T10:28:24.447607Z","steps":["trace[1933328737] 'agreement among raft nodes before linearized reading' (duration: 599.969221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.447625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:23.84758Z","time spent":"600.042782ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:24.447648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.277217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:24.447667Z","caller":"traceutil/trace.go:171","msg":"trace[692966094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"828.325259ms","start":"2026-02-03T10:28:23.619336Z","end":"2026-02-03T10:28:24.447662Z","steps":["trace[692966094] 'agreement among raft nodes before linearized reading' (duration: 828.295588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.447682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:23.619306Z","time spent":"828.373489ms","remote":"127.0.0.1:40626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:28:24.447719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.521026ms","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-03T10:28:24.447736Z","caller":"traceutil/trace.go:171","msg":"trace[1336137755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"663.560116ms","start":"2026-02-03T10:28:23.784171Z","end":"2026-02-03T10:28:24.447731Z","steps":["trace[1336137755] 'agreement among raft nodes before linearized reading' (duration: 663.519305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.447749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:23.784157Z","time spent":"663.587956ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:28:24.447795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.601661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:28:24.447814Z","caller":"traceutil/trace.go:171","msg":"trace[1938255484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:667; }","duration":"451.622691ms","start":"2026-02-03T10:28:23.996186Z","end":"2026-02-03T10:28:24.447809Z","steps":["trace[1938255484] 'agreement among raft nodes before linearized reading' (duration: 451.56832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:24.447835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:23.996139Z","time spent":"451.691162ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-03T10:28:25.645421Z","caller":"traceutil/trace.go:171","msg":"trace[22280703] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"352.040053ms","start":"2026-02-03T10:28:25.293352Z","end":"2026-02-03T10:28:25.645392Z","steps":["trace[22280703] 'process raft request' (duration: 351.941131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:25.645537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:25.293335Z","time spent":"352.130374ms","remote":"127.0.0.1:57190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:25.785284Z","caller":"traceutil/trace.go:171","msg":"trace[358613645] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"165.091529ms","start":"2026-02-03T10:28:25.620169Z","end":"2026-02-03T10:28:25.78526Z","steps":["trace[358613645] 'read index received' (duration: 25.265707ms)","trace[358613645] 'applied index is now lower than readState.Index' (duration: 139.825382ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:25.785378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.192691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:25.785408Z","caller":"traceutil/trace.go:171","msg":"trace[172183159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"165.264133ms","start":"2026-02-03T10:28:25.620139Z","end":"2026-02-03T10:28:25.785403Z","steps":["trace[172183159] 'agreement among raft nodes before linearized reading' (duration: 165.182921ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:25.785628Z","caller":"traceutil/trace.go:171","msg":"trace[1035708638] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"484.520389ms","start":"2026-02-03T10:28:25.301101Z","end":"2026-02-03T10:28:25.785622Z","steps":["trace[1035708638] 'process raft request' (duration: 484.07363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:25.785708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:25.301073Z","time spent":"484.602ms","remote":"127.0.0.1:57166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:33.020172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672063781290235,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T10:28:33.28305Z","caller":"traceutil/trace.go:171","msg":"trace[512262593] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"763.41572ms","start":"2026-02-03T10:28:32.519616Z","end":"2026-02-03T10:28:33.283032Z","steps":["trace[512262593] 'read index received' (duration: 763.284246ms)","trace[512262593] 'applied index is now lower than readState.Index' (duration: 130.844µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:28:33.283251Z","caller":"traceutil/trace.go:171","msg":"trace[1876814213] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"773.681691ms","start":"2026-02-03T10:28:32.509562Z","end":"2026-02-03T10:28:33.283244Z","steps":["trace[1876814213] 'process raft request' (duration: 773.376915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:33.283341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:32.509551Z","time spent":"773.721982ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:33.283698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.079542ms","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-03T10:28:33.283721Z","caller":"traceutil/trace.go:171","msg":"trace[1295491438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"764.116302ms","start":"2026-02-03T10:28:32.519597Z","end":"2026-02-03T10:28:33.283713Z","steps":["trace[1295491438] 'agreement among raft nodes before linearized reading' (duration: 764.041451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:33.283739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:32.519589Z","time spent":"764.146223ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-03T10:28:33.284139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.673132ms","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-03T10:28:33.284169Z","caller":"traceutil/trace.go:171","msg":"trace[1754684608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"755.722143ms","start":"2026-02-03T10:28:32.528439Z","end":"2026-02-03T10:28:33.284161Z","steps":["trace[1754684608] 'agreement among raft nodes before linearized reading' (duration: 755.651442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:33.284195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:32.528432Z","time spent":"755.758733ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:33.284276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.855423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:28:33.284295Z","caller":"traceutil/trace.go:171","msg":"trace[271354670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"688.897954ms","start":"2026-02-03T10:28:32.595392Z","end":"2026-02-03T10:28:33.28429Z","steps":["trace[271354670] 'agreement among raft nodes before linearized reading' (duration: 688.849603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:33.284313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:32.59538Z","time spent":"688.927494ms","remote":"127.0.0.1:56998","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-03T10:28:33.284384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.730411ms","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-03T10:28:33.284417Z","caller":"traceutil/trace.go:171","msg":"trace[1121054727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"752.774872ms","start":"2026-02-03T10:28:32.531637Z","end":"2026-02-03T10:28:33.284412Z","steps":["trace[1121054727] 'agreement among raft nodes before linearized reading' (duration: 752.713011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:33.284436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:32.531631Z","time spent":"752.801322ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T10:28:33.681202Z","caller":"traceutil/trace.go:171","msg":"trace[1237354529] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"392.080224ms","start":"2026-02-03T10:28:33.289107Z","end":"2026-02-03T10:28:33.681187Z","steps":["trace[1237354529] 'process raft request' (duration: 377.515217ms)","trace[1237354529] 'compare' (duration: 14.253071ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:33.68145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:33.289087Z","time spent":"392.301207ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:33.807047Z","caller":"traceutil/trace.go:171","msg":"trace[1946047840] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:758; }","duration":"187.850467ms","start":"2026-02-03T10:28:33.619181Z","end":"2026-02-03T10:28:33.807032Z","steps":["trace[1946047840] 'read index received' (duration: 47.446578ms)","trace[1946047840] 'applied index is now lower than readState.Index' (duration: 140.403189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:33.807144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.952798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:33.807169Z","caller":"traceutil/trace.go:171","msg":"trace[1489398615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"188.01209ms","start":"2026-02-03T10:28:33.61915Z","end":"2026-02-03T10:28:33.807162Z","steps":["trace[1489398615] 'agreement among raft nodes before linearized reading' (duration: 187.945729ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:33.8074Z","caller":"traceutil/trace.go:171","msg":"trace[1179620574] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"513.67645ms","start":"2026-02-03T10:28:33.293716Z","end":"2026-02-03T10:28:33.807393Z","steps":["trace[1179620574] 'process raft request' (duration: 508.898076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:33.80746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:33.293706Z","time spent":"513.71496ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:33.807552Z","caller":"traceutil/trace.go:171","msg":"trace[791476806] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"513.762931ms","start":"2026-02-03T10:28:33.293784Z","end":"2026-02-03T10:28:33.807547Z","steps":["trace[791476806] 'process raft request' (duration: 513.199922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:33.807588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:33.293777Z","time spent":"513.792532ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:33.807686Z","caller":"traceutil/trace.go:171","msg":"trace[1886960660] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"513.791932ms","start":"2026-02-03T10:28:33.293888Z","end":"2026-02-03T10:28:33.80768Z","steps":["trace[1886960660] 'process raft request' (duration: 513.12165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:33.807722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:33.293882Z","time spent":"513.821762ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:39.489375Z","caller":"traceutil/trace.go:171","msg":"trace[274071860] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"184.437957ms","start":"2026-02-03T10:28:39.304673Z","end":"2026-02-03T10:28:39.489111Z","steps":["trace[274071860] 'process raft request' (duration: 184.296214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.008672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.838775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:40.008736Z","caller":"traceutil/trace.go:171","msg":"trace[772927017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"384.932656ms","start":"2026-02-03T10:28:39.623786Z","end":"2026-02-03T10:28:40.008719Z","steps":["trace[772927017] 'range keys from in-memory index tree' (duration: 384.784024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.008763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:39.623772Z","time spent":"384.985988ms","remote":"127.0.0.1:40626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:28:40.008908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.986997ms","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-03T10:28:40.008927Z","caller":"traceutil/trace.go:171","msg":"trace[145431854] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:759; }","duration":"385.011888ms","start":"2026-02-03T10:28:39.623909Z","end":"2026-02-03T10:28:40.00892Z","steps":["trace[145431854] 'range keys from in-memory index tree' (duration: 384.963957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.008945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:39.623893Z","time spent":"385.046389ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-03T10:28:40.009044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.067019ms","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-03T10:28:40.009063Z","caller":"traceutil/trace.go:171","msg":"trace[2066348702] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:759; }","duration":"385.094749ms","start":"2026-02-03T10:28:39.623962Z","end":"2026-02-03T10:28:40.009057Z","steps":["trace[2066348702] 'range keys from in-memory index tree' (duration: 385.046259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.009099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:39.623959Z","time spent":"385.13468ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-03T10:28:40.009186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.1531ms","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-03T10:28:40.009204Z","caller":"traceutil/trace.go:171","msg":"trace[659675859] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:759; }","duration":"385.200482ms","start":"2026-02-03T10:28:39.623997Z","end":"2026-02-03T10:28:40.009198Z","steps":["trace[659675859] 'count revisions from in-memory index tree' (duration: 385.074019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.009224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:39.623967Z","time spent":"385.252633ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-03T10:28:40.00941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.721751ms","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-03T10:28:40.009428Z","caller":"traceutil/trace.go:171","msg":"trace[2147353505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"304.768801ms","start":"2026-02-03T10:28:39.704653Z","end":"2026-02-03T10:28:40.009422Z","steps":["trace[2147353505] 'range keys from in-memory index tree' (duration: 304.611769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.009444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:39.70464Z","time spent":"304.800202ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T10:28:40.009576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.988913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:28:40.009647Z","caller":"traceutil/trace.go:171","msg":"trace[349412323] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:759; }","duration":"225.079085ms","start":"2026-02-03T10:28:39.784558Z","end":"2026-02-03T10:28:40.009637Z","steps":["trace[349412323] 'count revisions from in-memory index tree' (duration: 224.947172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.009843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.573308ms","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-03T10:28:40.009864Z","caller":"traceutil/trace.go:171","msg":"trace[1050352911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"180.608299ms","start":"2026-02-03T10:28:39.82925Z","end":"2026-02-03T10:28:40.009858Z","steps":["trace[1050352911] 'range keys from in-memory index tree' (duration: 180.515878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.009983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.581386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:28:40.010001Z","caller":"traceutil/trace.go:171","msg":"trace[1074135046] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"177.607506ms","start":"2026-02-03T10:28:39.832387Z","end":"2026-02-03T10:28:40.009995Z","steps":["trace[1074135046] 'range keys from in-memory index tree' (duration: 177.539745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:40.010154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.769618ms","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-03T10:28:40.010203Z","caller":"traceutil/trace.go:171","msg":"trace[838142719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"177.84594ms","start":"2026-02-03T10:28:39.832345Z","end":"2026-02-03T10:28:40.010191Z","steps":["trace[838142719] 'range keys from in-memory index tree' (duration: 177.641836ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:28:50.378498Z","caller":"traceutil/trace.go:171","msg":"trace[1733760306] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"217.929887ms","start":"2026-02-03T10:28:50.160545Z","end":"2026-02-03T10:28:50.378475Z","steps":["trace[1733760306] 'process raft request' (duration: 217.806825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:50.657117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.02787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T10:28:50.658019Z","caller":"traceutil/trace.go:171","msg":"trace[1548587698] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"497.29811ms","start":"2026-02-03T10:28:50.160698Z","end":"2026-02-03T10:28:50.657996Z","steps":["trace[1548587698] 'process raft request' (duration: 330.219691ms)","trace[1548587698] 'compare' (duration: 165.883078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:50.658187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:50.16069Z","time spent":"497.419692ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T10:28:50.658511Z","caller":"traceutil/trace.go:171","msg":"trace[294828301] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"496.14341ms","start":"2026-02-03T10:28:50.16236Z","end":"2026-02-03T10:28:50.658504Z","steps":["trace[294828301] 'process raft request' (duration: 494.874508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:50.658603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:50.162351Z","time spent":"496.225641ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:28:50.991036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.331846ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T10:28:50.991152Z","caller":"traceutil/trace.go:171","msg":"trace[188515213] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:815; }","duration":"371.112963ms","start":"2026-02-03T10:28:50.620023Z","end":"2026-02-03T10:28:50.991136Z","steps":["trace[188515213] 'read index received' (duration: 41.431µs)","trace[188515213] 'applied index is now lower than readState.Index' (duration: 371.070202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:28:50.99173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.218564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:28:50.991773Z","caller":"traceutil/trace.go:171","msg":"trace[1115492398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"371.739853ms","start":"2026-02-03T10:28:50.620017Z","end":"2026-02-03T10:28:50.991757Z","steps":["trace[1115492398] 'agreement among raft nodes before linearized reading' (duration: 371.193294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:28:50.991807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:28:50.619977Z","time spent":"371.822125ms","remote":"127.0.0.1:40626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T10:29:02.90528Z","caller":"traceutil/trace.go:171","msg":"trace[1959908905] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"191.725325ms","start":"2026-02-03T10:29:02.7135Z","end":"2026-02-03T10:29:02.905226Z","steps":["trace[1959908905] 'process raft request' (duration: 191.509722ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:02.9059Z","caller":"traceutil/trace.go:171","msg":"trace[1616247068] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"191.734816ms","start":"2026-02-03T10:29:02.714155Z","end":"2026-02-03T10:29:02.90589Z","steps":["trace[1616247068] 'read index received' (duration: 190.729868ms)","trace[1616247068] 'applied index is now lower than readState.Index' (duration: 1.003998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:29:02.906132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.938649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:29:02.906169Z","caller":"traceutil/trace.go:171","msg":"trace[1232625190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:874; }","duration":"192.01098ms","start":"2026-02-03T10:29:02.714144Z","end":"2026-02-03T10:29:02.906155Z","steps":["trace[1232625190] 'agreement among raft nodes before linearized reading' (duration: 191.840357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:02.906412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.07824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:29:02.906444Z","caller":"traceutil/trace.go:171","msg":"trace[2107044487] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:874; }","duration":"120.148012ms","start":"2026-02-03T10:29:02.786287Z","end":"2026-02-03T10:29:02.906435Z","steps":["trace[2107044487] 'agreement among raft nodes before linearized reading' (duration: 120.0492ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:04.102074Z","caller":"traceutil/trace.go:171","msg":"trace[1881481776] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"479.528866ms","start":"2026-02-03T10:29:03.622529Z","end":"2026-02-03T10:29:04.102058Z","steps":["trace[1881481776] 'read index received' (duration: 479.396894ms)","trace[1881481776] 'applied index is now lower than readState.Index' (duration: 131.162µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:04.102184Z","caller":"traceutil/trace.go:171","msg":"trace[1728977197] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"509.275542ms","start":"2026-02-03T10:29:03.592903Z","end":"2026-02-03T10:29:04.102179Z","steps":["trace[1728977197] 'process raft request' (duration: 509.032958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.102252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.59289Z","time spent":"509.309273ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T10:29:04.102338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.808332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T10:29:04.102354Z","caller":"traceutil/trace.go:171","msg":"trace[535732224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"479.846882ms","start":"2026-02-03T10:29:03.622501Z","end":"2026-02-03T10:29:04.102347Z","steps":["trace[535732224] 'agreement among raft nodes before linearized reading' (duration: 479.820991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.102367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:03.622485Z","time spent":"479.879542ms","remote":"127.0.0.1:40626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T10:29:04.102509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.773656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:29:04.102523Z","caller":"traceutil/trace.go:171","msg":"trace[1644369653] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:876; }","duration":"276.822478ms","start":"2026-02-03T10:29:03.825696Z","end":"2026-02-03T10:29:04.102519Z","steps":["trace[1644369653] 'agreement among raft nodes before linearized reading' (duration: 276.793607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.763707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.339585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-03T10:29:04.763788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.952003ms","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-03T10:29:04.763826Z","caller":"traceutil/trace.go:171","msg":"trace[1170900160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:876; }","duration":"137.505258ms","start":"2026-02-03T10:29:04.626297Z","end":"2026-02-03T10:29:04.763802Z","steps":["trace[1170900160] 'range keys from in-memory index tree' (duration: 137.281085ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:04.763841Z","caller":"traceutil/trace.go:171","msg":"trace[1694470919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"272.056384ms","start":"2026-02-03T10:29:04.491773Z","end":"2026-02-03T10:29:04.763829Z","steps":["trace[1694470919] 'range keys from in-memory index tree' (duration: 271.816851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.764106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.151353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-03T10:29:04.764122Z","caller":"traceutil/trace.go:171","msg":"trace[1093237227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:876; }","duration":"559.202063ms","start":"2026-02-03T10:29:04.204914Z","end":"2026-02-03T10:29:04.764116Z","steps":["trace[1093237227] 'range keys from in-memory index tree' (duration: 559.065311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:04.764136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:29:04.204894Z","time spent":"559.237554ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T10:29:04.885031Z","caller":"traceutil/trace.go:171","msg":"trace[778785753] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"115.912557ms","start":"2026-02-03T10:29:04.769102Z","end":"2026-02-03T10:29:04.885015Z","steps":["trace[778785753] 'process raft request' (duration: 115.805695ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:04.887778Z","caller":"traceutil/trace.go:171","msg":"trace[1334973147] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"115.937077ms","start":"2026-02-03T10:29:04.771823Z","end":"2026-02-03T10:29:04.88776Z","steps":["trace[1334973147] 'process raft request' (duration: 115.838616ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:13.116967Z","caller":"traceutil/trace.go:171","msg":"trace[2031574474] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"194.461093ms","start":"2026-02-03T10:29:12.922492Z","end":"2026-02-03T10:29:13.116953Z","steps":["trace[2031574474] 'read index received' (duration: 194.332031ms)","trace[2031574474] 'applied index is now lower than readState.Index' (duration: 128.552µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:13.117046Z","caller":"traceutil/trace.go:171","msg":"trace[1194392412] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"207.455503ms","start":"2026-02-03T10:29:12.909585Z","end":"2026-02-03T10:29:13.117041Z","steps":["trace[1194392412] 'process raft request' (duration: 207.28141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:13.117222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.630275ms","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-03T10:29:13.117279Z","caller":"traceutil/trace.go:171","msg":"trace[1796036405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"169.705467ms","start":"2026-02-03T10:29:12.947563Z","end":"2026-02-03T10:29:13.117268Z","steps":["trace[1796036405] 'agreement among raft nodes before linearized reading' (duration: 169.570124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:13.117324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.819709ms","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-03T10:29:13.117354Z","caller":"traceutil/trace.go:171","msg":"trace[703837012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"194.870991ms","start":"2026-02-03T10:29:12.922477Z","end":"2026-02-03T10:29:13.117348Z","steps":["trace[703837012] 'agreement among raft nodes before linearized reading' (duration: 194.767439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:13.11745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.820189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T10:29:13.117463Z","caller":"traceutil/trace.go:171","msg":"trace[38437351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"169.840419ms","start":"2026-02-03T10:29:12.947619Z","end":"2026-02-03T10:29:13.11746Z","steps":["trace[38437351] 'agreement among raft nodes before linearized reading' (duration: 169.773738ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:18.278901Z","caller":"traceutil/trace.go:171","msg":"trace[36710816] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"102.48951ms","start":"2026-02-03T10:29:18.176396Z","end":"2026-02-03T10:29:18.278886Z","steps":["trace[36710816] 'process raft request' (duration: 102.381098ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:20.403535Z","caller":"traceutil/trace.go:171","msg":"trace[21031118] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"116.590709ms","start":"2026-02-03T10:29:20.286924Z","end":"2026-02-03T10:29:20.403514Z","steps":["trace[21031118] 'process raft request' (duration: 116.479437ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:26.820378Z","caller":"traceutil/trace.go:171","msg":"trace[2060582466] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"181.905363ms","start":"2026-02-03T10:29:26.63816Z","end":"2026-02-03T10:29:26.820066Z","steps":["trace[2060582466] 'process raft request' (duration: 181.76152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:27.111652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.304819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:29:27.111727Z","caller":"traceutil/trace.go:171","msg":"trace[1575711500] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:966; }","duration":"131.427311ms","start":"2026-02-03T10:29:26.980282Z","end":"2026-02-03T10:29:27.111709Z","steps":["trace[1575711500] 'count revisions from in-memory index tree' (duration: 131.244908ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:29:27.435105Z","caller":"traceutil/trace.go:171","msg":"trace[905965166] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"225.327289ms","start":"2026-02-03T10:29:27.209759Z","end":"2026-02-03T10:29:27.435086Z","steps":["trace[905965166] 'read index received' (duration: 225.174636ms)","trace[905965166] 'applied index is now lower than readState.Index' (duration: 151.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T10:29:27.435219Z","caller":"traceutil/trace.go:171","msg":"trace[1810839478] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"240.754112ms","start":"2026-02-03T10:29:27.194438Z","end":"2026-02-03T10:29:27.435192Z","steps":["trace[1810839478] 'process raft request' (duration: 240.545998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:27.435467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.776579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T10:29:27.435529Z","caller":"traceutil/trace.go:171","msg":"trace[1750568628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"211.853671ms","start":"2026-02-03T10:29:27.223656Z","end":"2026-02-03T10:29:27.43551Z","steps":["trace[1750568628] 'agreement among raft nodes before linearized reading' (duration: 211.670588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:27.435761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.00515ms","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-03T10:29:27.435814Z","caller":"traceutil/trace.go:171","msg":"trace[1681009966] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"226.082871ms","start":"2026-02-03T10:29:27.209723Z","end":"2026-02-03T10:29:27.435806Z","steps":["trace[1681009966] 'agreement among raft nodes before linearized reading' (duration: 225.979609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:27.435956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.324118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T10:29:27.43598Z","caller":"traceutil/trace.go:171","msg":"trace[978456830] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:967; }","duration":"204.373749ms","start":"2026-02-03T10:29:27.231599Z","end":"2026-02-03T10:29:27.435972Z","steps":["trace[978456830] 'agreement among raft nodes before linearized reading' (duration: 204.330568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:29:27.436167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.137796ms","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-03T10:29:27.436193Z","caller":"traceutil/trace.go:171","msg":"trace[2134106297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"212.171966ms","start":"2026-02-03T10:29:27.224014Z","end":"2026-02-03T10:29:27.436186Z","steps":["trace[2134106297] 'agreement among raft nodes before linearized reading' (duration: 212.095855ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T10:33:16.346174Z","caller":"traceutil/trace.go:171","msg":"trace[1156832587] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"404.423897ms","start":"2026-02-03T10:33:15.941725Z","end":"2026-02-03T10:33:16.346149Z","steps":["trace[1156832587] 'read index received' (duration: 404.245024ms)","trace[1156832587] 'applied index is now lower than readState.Index' (duration: 177.963µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T10:33:16.347144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.400025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-9n8p2\" ","response":"range_response_count:1 size:11191"} {"level":"info","ts":"2026-02-03T10:33:16.347245Z","caller":"traceutil/trace.go:171","msg":"trace[1264466177] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-9n8p2; range_end:; response_count:1; response_revision:1967; }","duration":"405.543788ms","start":"2026-02-03T10:33:15.941688Z","end":"2026-02-03T10:33:16.347231Z","steps":["trace[1264466177] 'agreement among raft nodes before linearized reading' (duration: 405.318064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:33:16.347339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:33:15.941668Z","time spent":"405.6581ms","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11214,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-9n8p2\" "} {"level":"warn","ts":"2026-02-03T10:33:16.347907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.999711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-sh7p9\" ","response":"range_response_count:1 size:13263"} {"level":"info","ts":"2026-02-03T10:33:16.348014Z","caller":"traceutil/trace.go:171","msg":"trace[315570157] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-sh7p9; range_end:; response_count:1; response_revision:1967; }","duration":"359.139763ms","start":"2026-02-03T10:33:15.988861Z","end":"2026-02-03T10:33:16.348001Z","steps":["trace[315570157] 'agreement among raft nodes before linearized reading' (duration: 358.925739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:33:16.3481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:33:15.988848Z","time spent":"359.240006ms","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":13286,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-sh7p9\" "} {"level":"info","ts":"2026-02-03T10:33:16.346585Z","caller":"traceutil/trace.go:171","msg":"trace[299194066] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"431.559829ms","start":"2026-02-03T10:33:15.914994Z","end":"2026-02-03T10:33:16.346554Z","steps":["trace[299194066] 'process raft request' (duration: 431.022659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T10:33:16.352185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T10:33:15.914964Z","time spent":"437.148572ms","remote":"127.0.0.1:56998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}