{"level":"warn","ts":"2026-02-11T01:50:44.144857Z","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-11T01:50:44.145024Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-11T01:50:44.145105Z","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-11T01:50:44.145115Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-02-11T01:50:44.145132Z","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-11T01:50:44.145857Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-02-11T01:50:44.145928Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-11T01:50:44.151843Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.902403ms"} {"level":"info","ts":"2026-02-11T01:50:44.175267Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-02-11T01:50:44.175333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:50:44.175349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-02-11T01:50:44.175358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T01:50:44.175366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-02-11T01:50:44.175394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-02-11T01:50:44.186201Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:50:44.190715Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:50:44.197504Z","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-11T01:50:44.212287Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T01:50:44.21267Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T01:50:44.212841Z","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-11T01:50:44.212902Z","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-11T01:50:44.212921Z","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-11T01:50:44.213514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-02-11T01:50:44.213607Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-02-11T01:50:44.233143Z","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-11T01:50:44.233588Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T01:50:44.233612Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:50:44.233772Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-02-11T01:50:44.233812Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-02-11T01:50:44.977082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:50:44.977124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:50:44.977172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-02-11T01:50:44.977183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:50:44.977189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-02-11T01:50:44.977197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-02-11T01:50:44.977203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-02-11T01:50:44.978742Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T01:50:44.978876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:50:44.979196Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:50:44.979287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:50:44.980025Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:50:44.980041Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:50:44.984052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T01:50:45.000574Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-02-11T01:50:45.031844Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:50:45.032477Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:50:45.032555Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:50:46.756539Z","caller":"traceutil/trace.go:171","msg":"trace[884499506] linearizableReadLoop","detail":"{readStateIndex:34; appliedIndex:33; }","duration":"185.711892ms","start":"2026-02-11T01:50:46.570807Z","end":"2026-02-11T01:50:46.756519Z","steps":["trace[884499506] 'read index received' (duration: 25.189769ms)","trace[884499506] 'applied index is now lower than readState.Index' (duration: 160.521193ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:46.75673Z","caller":"traceutil/trace.go:171","msg":"trace[1929112646] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"182.338975ms","start":"2026-02-11T01:50:46.574376Z","end":"2026-02-11T01:50:46.756715Z","steps":["trace[1929112646] 'process raft request' (duration: 182.006427ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:46.757157Z","caller":"traceutil/trace.go:171","msg":"trace[1383836842] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"186.470479ms","start":"2026-02-11T01:50:46.570673Z","end":"2026-02-11T01:50:46.757143Z","steps":["trace[1383836842] 'process raft request' (duration: 151.351854ms)","trace[1383836842] 'compare' (duration: 34.254986ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:46.758616Z","caller":"traceutil/trace.go:171","msg":"trace[347927850] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"182.315614ms","start":"2026-02-11T01:50:46.576286Z","end":"2026-02-11T01:50:46.758602Z","steps":["trace[347927850] 'process raft request' (duration: 180.180595ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:46.759649Z","caller":"traceutil/trace.go:171","msg":"trace[384649592] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"183.279546ms","start":"2026-02-11T01:50:46.576355Z","end":"2026-02-11T01:50:46.759635Z","steps":["trace[384649592] 'process raft request' (duration: 180.134943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:46.76003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.203142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-11T01:50:46.760084Z","caller":"traceutil/trace.go:171","msg":"trace[380177187] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:35; }","duration":"189.283174ms","start":"2026-02-11T01:50:46.570789Z","end":"2026-02-11T01:50:46.760072Z","steps":["trace[380177187] 'agreement among raft nodes before linearized reading' (duration: 189.11656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:46.763076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.908313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T01:50:46.763127Z","caller":"traceutil/trace.go:171","msg":"trace[839429654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:35; }","duration":"184.971795ms","start":"2026-02-11T01:50:46.578143Z","end":"2026-02-11T01:50:46.763114Z","steps":["trace[839429654] 'agreement among raft nodes before linearized reading' (duration: 184.889343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:46.764377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.843486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:50:46.764434Z","caller":"traceutil/trace.go:171","msg":"trace[49707769] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:35; }","duration":"165.924617ms","start":"2026-02-11T01:50:46.598498Z","end":"2026-02-11T01:50:46.764422Z","steps":["trace[49707769] 'agreement among raft nodes before linearized reading' (duration: 165.805685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:46.765415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.103954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-11T01:50:46.765435Z","caller":"traceutil/trace.go:171","msg":"trace[1859932501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:35; }","duration":"187.133845ms","start":"2026-02-11T01:50:46.578297Z","end":"2026-02-11T01:50:46.765431Z","steps":["trace[1859932501] 'agreement among raft nodes before linearized reading' (duration: 187.099094ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:46.763302Z","caller":"traceutil/trace.go:171","msg":"trace[95078804] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"187.909723ms","start":"2026-02-11T01:50:46.57538Z","end":"2026-02-11T01:50:46.76329Z","steps":["trace[95078804] 'process raft request' (duration: 181.037995ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:46.763336Z","caller":"traceutil/trace.go:171","msg":"trace[1535824056] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"187.108463ms","start":"2026-02-11T01:50:46.57622Z","end":"2026-02-11T01:50:46.763328Z","steps":["trace[1535824056] 'process raft request' (duration: 180.226635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:54.221489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.534577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-11T01:50:54.221733Z","caller":"traceutil/trace.go:171","msg":"trace[579132703] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:296; }","duration":"120.790982ms","start":"2026-02-11T01:50:54.100921Z","end":"2026-02-11T01:50:54.221711Z","steps":["trace[579132703] 'range keys from in-memory index tree' (duration: 120.423764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:54.22134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.60843ms","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-11T01:50:54.222478Z","caller":"traceutil/trace.go:171","msg":"trace[735479483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:296; }","duration":"118.773866ms","start":"2026-02-11T01:50:54.103686Z","end":"2026-02-11T01:50:54.222459Z","steps":["trace[735479483] 'range keys from in-memory index tree' (duration: 117.526857ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:55.377828Z","caller":"traceutil/trace.go:171","msg":"trace[1399405703] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"112.957582ms","start":"2026-02-11T01:50:55.264853Z","end":"2026-02-11T01:50:55.377811Z","steps":["trace[1399405703] 'process raft request' (duration: 90.109698ms)","trace[1399405703] 'compare' (duration: 22.536457ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:55.954907Z","caller":"traceutil/trace.go:171","msg":"trace[1198792477] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:322; }","duration":"154.374813ms","start":"2026-02-11T01:50:55.800519Z","end":"2026-02-11T01:50:55.954894Z","steps":["trace[1198792477] 'read index received' (duration: 49.840065ms)","trace[1198792477] 'applied index is now lower than readState.Index' (duration: 104.533998ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:55.955151Z","caller":"traceutil/trace.go:171","msg":"trace[234644324] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"158.6089ms","start":"2026-02-11T01:50:55.796534Z","end":"2026-02-11T01:50:55.955143Z","steps":["trace[234644324] 'process raft request' (duration: 152.668654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:55.955356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.826384ms","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-11T01:50:55.955454Z","caller":"traceutil/trace.go:171","msg":"trace[1410579546] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:319; }","duration":"154.948796ms","start":"2026-02-11T01:50:55.800495Z","end":"2026-02-11T01:50:55.955444Z","steps":["trace[1410579546] 'agreement among raft nodes before linearized reading' (duration: 154.826263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:55.95559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.062835ms","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-11T01:50:55.955645Z","caller":"traceutil/trace.go:171","msg":"trace[355004700] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:319; }","duration":"103.146767ms","start":"2026-02-11T01:50:55.85249Z","end":"2026-02-11T01:50:55.955637Z","steps":["trace[355004700] 'agreement among raft nodes before linearized reading' (duration: 103.076996ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:55.955338Z","caller":"traceutil/trace.go:171","msg":"trace[92612038] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"156.160084ms","start":"2026-02-11T01:50:55.799151Z","end":"2026-02-11T01:50:55.955311Z","steps":["trace[92612038] 'process raft request' (duration: 155.713794ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:55.956182Z","caller":"traceutil/trace.go:171","msg":"trace[1556430243] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"159.534491ms","start":"2026-02-11T01:50:55.79664Z","end":"2026-02-11T01:50:55.956174Z","steps":["trace[1556430243] 'process raft request' (duration: 158.157239ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:55.956335Z","caller":"traceutil/trace.go:171","msg":"trace[1435177076] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"158.985378ms","start":"2026-02-11T01:50:55.797341Z","end":"2026-02-11T01:50:55.956326Z","steps":["trace[1435177076] 'process raft request' (duration: 157.484244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:50:56.265142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.831251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:50:56.267052Z","caller":"traceutil/trace.go:171","msg":"trace[976096163] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:332; }","duration":"209.963479ms","start":"2026-02-11T01:50:56.057076Z","end":"2026-02-11T01:50:56.26704Z","steps":["trace[976096163] 'read index received' (duration: 19.134189ms)","trace[976096163] 'applied index is now lower than readState.Index' (duration: 190.82869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:50:56.267263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.163113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-11T01:50:56.272718Z","caller":"traceutil/trace.go:171","msg":"trace[1914054192] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:331; }","duration":"215.648519ms","start":"2026-02-11T01:50:56.057058Z","end":"2026-02-11T01:50:56.272706Z","steps":["trace[1914054192] 'agreement among raft nodes before linearized reading' (duration: 210.119942ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:56.267289Z","caller":"traceutil/trace.go:171","msg":"trace[362689797] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"259.484565ms","start":"2026-02-11T01:50:56.007797Z","end":"2026-02-11T01:50:56.267281Z","steps":["trace[362689797] 'process raft request' (duration: 68.458441ms)","trace[362689797] 'compare' (duration: 187.434142ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:50:56.267381Z","caller":"traceutil/trace.go:171","msg":"trace[797512373] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"210.354997ms","start":"2026-02-11T01:50:56.05702Z","end":"2026-02-11T01:50:56.267375Z","steps":["trace[797512373] 'process raft request' (duration: 208.682619ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:56.267464Z","caller":"traceutil/trace.go:171","msg":"trace[1572896619] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"209.17023ms","start":"2026-02-11T01:50:56.058288Z","end":"2026-02-11T01:50:56.267458Z","steps":["trace[1572896619] 'process raft request' (duration: 208.605527ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:56.267497Z","caller":"traceutil/trace.go:171","msg":"trace[74874934] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"206.309724ms","start":"2026-02-11T01:50:56.061183Z","end":"2026-02-11T01:50:56.267493Z","steps":["trace[74874934] 'process raft request' (duration: 205.760251ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:56.267513Z","caller":"traceutil/trace.go:171","msg":"trace[993738633] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"205.777112ms","start":"2026-02-11T01:50:56.061732Z","end":"2026-02-11T01:50:56.267509Z","steps":["trace[993738633] 'process raft request' (duration: 205.23513ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:50:56.269828Z","caller":"traceutil/trace.go:171","msg":"trace[540442263] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"198.161798ms","start":"2026-02-11T01:50:56.071657Z","end":"2026-02-11T01:50:56.269819Z","steps":["trace[540442263] 'process raft request' (duration: 195.358573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:02.837872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242013262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:51:03.327835Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.152948849s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:51:03.328228Z","caller":"traceutil/trace.go:171","msg":"trace[1697571798] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:389; }","duration":"990.813799ms","start":"2026-02-11T01:51:02.337391Z","end":"2026-02-11T01:51:03.328205Z","steps":["trace[1697571798] 'read index received' (duration: 990.806069ms)","trace[1697571798] 'applied index is now lower than readState.Index' (duration: 6.47µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:03.328225Z","caller":"traceutil/trace.go:171","msg":"trace[325971015] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"1.15341151s","start":"2026-02-11T01:51:02.174797Z","end":"2026-02-11T01:51:03.328208Z","steps":["trace[325971015] 'process raft request' (duration: 1.153267537s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:03.328413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.005173ms","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-11T01:51:03.328447Z","caller":"traceutil/trace.go:171","msg":"trace[1879481954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:380; }","duration":"991.117835ms","start":"2026-02-11T01:51:02.337318Z","end":"2026-02-11T01:51:03.328436Z","steps":["trace[1879481954] 'agreement among raft nodes before linearized reading' (duration: 990.967892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:03.328482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:02.174781Z","time spent":"1.153502083s","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:03.328498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:02.337296Z","time spent":"991.187457ms","remote":"127.0.0.1:49992","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":"warn","ts":"2026-02-11T01:51:04.440565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.090623ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T01:51:04.440756Z","caller":"traceutil/trace.go:171","msg":"trace[1938437628] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"1.112457279s","start":"2026-02-11T01:51:03.328265Z","end":"2026-02-11T01:51:04.440722Z","steps":["trace[1938437628] 'read index received' (duration: 233.208952ms)","trace[1938437628] 'applied index is now lower than readState.Index' (duration: 879.243527ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:04.440834Z","caller":"traceutil/trace.go:171","msg":"trace[913577869] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"1.101459266s","start":"2026-02-11T01:51:03.339363Z","end":"2026-02-11T01:51:04.440822Z","steps":["trace[913577869] 'process raft request' (duration: 1.101372574s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:04.44092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:03.339358Z","time spent":"1.101517237s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:04.440936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.971017922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:04.440992Z","caller":"traceutil/trace.go:171","msg":"trace[1260242466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"1.971103864s","start":"2026-02-11T01:51:02.469854Z","end":"2026-02-11T01:51:04.440958Z","steps":["trace[1260242466] 'agreement among raft nodes before linearized reading' (duration: 1.970965071s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:04.441022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:02.469835Z","time spent":"1.971179796s","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:51:04.441176Z","caller":"traceutil/trace.go:171","msg":"trace[869664137] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"1.101868146s","start":"2026-02-11T01:51:03.3393Z","end":"2026-02-11T01:51:04.441169Z","steps":["trace[869664137] 'process raft request' (duration: 1.101346804s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:04.44121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:03.339282Z","time spent":"1.101912187s","remote":"127.0.0.1:50198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:51:04.441247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509323257s","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-11T01:51:04.441271Z","caller":"traceutil/trace.go:171","msg":"trace[891441195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:382; }","duration":"1.509390168s","start":"2026-02-11T01:51:02.931875Z","end":"2026-02-11T01:51:04.441265Z","steps":["trace[891441195] 'agreement among raft nodes before linearized reading' (duration: 1.509333907s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:04.441288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:02.931858Z","time spent":"1.509425749s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:04.441355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510404961s","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-11T01:51:04.441371Z","caller":"traceutil/trace.go:171","msg":"trace[1194526947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:382; }","duration":"1.510476783s","start":"2026-02-11T01:51:02.930889Z","end":"2026-02-11T01:51:04.441366Z","steps":["trace[1194526947] 'agreement among raft nodes before linearized reading' (duration: 1.510447132s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:04.441387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:02.93087Z","time spent":"1.510512464s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:04.441537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.611704316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:51:04.441562Z","caller":"traceutil/trace.go:171","msg":"trace[242167662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:382; }","duration":"1.611762808s","start":"2026-02-11T01:51:02.829791Z","end":"2026-02-11T01:51:04.441554Z","steps":["trace[242167662] 'agreement among raft nodes before linearized reading' (duration: 1.611720237s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:04.441582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:02.829769Z","time spent":"1.611808959s","remote":"127.0.0.1:49992","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":"info","ts":"2026-02-11T01:51:06.727459Z","caller":"traceutil/trace.go:171","msg":"trace[1262994798] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"207.175944ms","start":"2026-02-11T01:51:06.520266Z","end":"2026-02-11T01:51:06.727441Z","steps":["trace[1262994798] 'process raft request' (duration: 207.151084ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:06.727674Z","caller":"traceutil/trace.go:171","msg":"trace[41158477] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"215.618087ms","start":"2026-02-11T01:51:06.512047Z","end":"2026-02-11T01:51:06.727666Z","steps":["trace[41158477] 'process raft request' (duration: 130.597266ms)","trace[41158477] 'compare' (duration: 84.363746ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:06.727727Z","caller":"traceutil/trace.go:171","msg":"trace[1242729396] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"207.552433ms","start":"2026-02-11T01:51:06.52017Z","end":"2026-02-11T01:51:06.727723Z","steps":["trace[1242729396] 'process raft request' (duration: 207.214885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:17.245861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.79185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:17.246024Z","caller":"traceutil/trace.go:171","msg":"trace[2110818341] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"312.333008ms","start":"2026-02-11T01:51:16.933674Z","end":"2026-02-11T01:51:17.246007Z","steps":["trace[2110818341] 'process raft request' (duration: 312.278736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:17.246088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:16.933668Z","time spent":"312.386539ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:17.246225Z","caller":"traceutil/trace.go:171","msg":"trace[1557869817] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"313.355031ms","start":"2026-02-11T01:51:16.932844Z","end":"2026-02-11T01:51:17.246199Z","steps":["trace[1557869817] 'process raft request' (duration: 40.151222ms)","trace[1557869817] 'compare' (duration: 271.904129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:17.246352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:16.932834Z","time spent":"313.447543ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:17.644887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.730722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:17.64543Z","caller":"traceutil/trace.go:171","msg":"trace[236129414] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"623.725443ms","start":"2026-02-11T01:51:17.02167Z","end":"2026-02-11T01:51:17.645395Z","steps":["trace[236129414] 'process raft request' (duration: 331.536268ms)","trace[236129414] 'compare' (duration: 290.619899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:17.645512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:17.021654Z","time spent":"623.818656ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:17.646942Z","caller":"traceutil/trace.go:171","msg":"trace[1143199814] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:488; }","duration":"589.177281ms","start":"2026-02-11T01:51:17.057751Z","end":"2026-02-11T01:51:17.646928Z","steps":["trace[1143199814] 'read index received' (duration: 158.553388ms)","trace[1143199814] 'applied index is now lower than readState.Index' (duration: 430.622423ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:17.647058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.268793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:17.647087Z","caller":"traceutil/trace.go:171","msg":"trace[1704035977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"589.353255ms","start":"2026-02-11T01:51:17.057725Z","end":"2026-02-11T01:51:17.647078Z","steps":["trace[1704035977] 'agreement among raft nodes before linearized reading' (duration: 589.270353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:17.647593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:17.057713Z","time spent":"589.864877ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:51:19.208519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.073443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:19.208567Z","caller":"traceutil/trace.go:171","msg":"trace[1018219040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"153.161905ms","start":"2026-02-11T01:51:19.055394Z","end":"2026-02-11T01:51:19.208556Z","steps":["trace[1018219040] 'range keys from in-memory index tree' (duration: 153.005511ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:19.446932Z","caller":"traceutil/trace.go:171","msg":"trace[914015468] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"190.933762ms","start":"2026-02-11T01:51:19.255983Z","end":"2026-02-11T01:51:19.446917Z","steps":["trace[914015468] 'process raft request' (duration: 190.85607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:19.867701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.834108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:19.867791Z","caller":"traceutil/trace.go:171","msg":"trace[2036658711] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"611.107484ms","start":"2026-02-11T01:51:19.256667Z","end":"2026-02-11T01:51:19.867775Z","steps":["trace[2036658711] 'process raft request' (duration: 302.135273ms)","trace[2036658711] 'compare' (duration: 308.732046ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:19.867847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:19.256662Z","time spent":"611.160125ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:20.976455Z","caller":"traceutil/trace.go:171","msg":"trace[1284830773] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"331.122699ms","start":"2026-02-11T01:51:20.645293Z","end":"2026-02-11T01:51:20.976416Z","steps":["trace[1284830773] 'process raft request' (duration: 330.973946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:20.976666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:20.645269Z","time spent":"331.278503ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:20.977155Z","caller":"traceutil/trace.go:171","msg":"trace[900812742] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:498; }","duration":"271.955371ms","start":"2026-02-11T01:51:20.705157Z","end":"2026-02-11T01:51:20.977112Z","steps":["trace[900812742] 'read index received' (duration: 271.942131ms)","trace[900812742] 'applied index is now lower than readState.Index' (duration: 11.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:20.977355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.209677ms","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-11T01:51:20.977418Z","caller":"traceutil/trace.go:171","msg":"trace[511055670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"272.3411ms","start":"2026-02-11T01:51:20.705053Z","end":"2026-02-11T01:51:20.977394Z","steps":["trace[511055670] 'agreement among raft nodes before linearized reading' (duration: 272.176616ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:21.396491Z","caller":"traceutil/trace.go:171","msg":"trace[967089763] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"413.594941ms","start":"2026-02-11T01:51:20.982878Z","end":"2026-02-11T01:51:21.396473Z","steps":["trace[967089763] 'process raft request' (duration: 413.481689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.3966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:20.982857Z","time spent":"413.683783ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:21.452097Z","caller":"traceutil/trace.go:171","msg":"trace[1138591267] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"393.835077ms","start":"2026-02-11T01:51:21.058218Z","end":"2026-02-11T01:51:21.452053Z","steps":["trace[1138591267] 'read index received' (duration: 338.702022ms)","trace[1138591267] 'applied index is now lower than readState.Index' (duration: 55.131485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:21.452289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.024441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:21.452343Z","caller":"traceutil/trace.go:171","msg":"trace[1679443228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"394.143944ms","start":"2026-02-11T01:51:21.058185Z","end":"2026-02-11T01:51:21.452329Z","steps":["trace[1679443228] 'agreement among raft nodes before linearized reading' (duration: 393.977361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.452378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:21.058168Z","time spent":"394.200425ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:51:21.452627Z","caller":"traceutil/trace.go:171","msg":"trace[429729200] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"469.488373ms","start":"2026-02-11T01:51:20.983124Z","end":"2026-02-11T01:51:21.452612Z","steps":["trace[429729200] 'process raft request' (duration: 468.834408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:21.452725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:20.98311Z","time spent":"469.559304ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:22.200017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.174267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:22.200677Z","caller":"traceutil/trace.go:171","msg":"trace[431336771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"140.886144ms","start":"2026-02-11T01:51:22.059771Z","end":"2026-02-11T01:51:22.200657Z","steps":["trace[431336771] 'range keys from in-memory index tree' (duration: 140.095025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:23.482964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.424886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-11T01:51:28.007967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242013509,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:51:28.051957Z","caller":"traceutil/trace.go:171","msg":"trace[1874188882] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"931.890357ms","start":"2026-02-11T01:51:27.120039Z","end":"2026-02-11T01:51:28.051929Z","steps":["trace[1874188882] 'process raft request' (duration: 931.740714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:28.052108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.120021Z","time spent":"932.008599ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:28.188694Z","caller":"traceutil/trace.go:171","msg":"trace[493639412] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"414.934492ms","start":"2026-02-11T01:51:27.773749Z","end":"2026-02-11T01:51:28.188683Z","steps":["trace[493639412] 'process raft request' (duration: 342.135782ms)","trace[493639412] 'compare' (duration: 72.521204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:28.188792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.773718Z","time spent":"415.024315ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:28.189322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.407181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:51:28.189348Z","caller":"traceutil/trace.go:171","msg":"trace[1895509267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"682.461541ms","start":"2026-02-11T01:51:27.506879Z","end":"2026-02-11T01:51:28.18934Z","steps":["trace[1895509267] 'agreement among raft nodes before linearized reading' (duration: 682.378419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:28.189368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.506866Z","time spent":"682.497343ms","remote":"127.0.0.1:49992","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":"info","ts":"2026-02-11T01:51:28.188517Z","caller":"traceutil/trace.go:171","msg":"trace[923711509] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:514; }","duration":"681.592462ms","start":"2026-02-11T01:51:27.506908Z","end":"2026-02-11T01:51:28.1885Z","steps":["trace[923711509] 'read index received' (duration: 544.799043ms)","trace[923711509] 'applied index is now lower than readState.Index' (duration: 136.792589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:28.189666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.556677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:28.189685Z","caller":"traceutil/trace.go:171","msg":"trace[487379117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"127.623269ms","start":"2026-02-11T01:51:28.062055Z","end":"2026-02-11T01:51:28.189679Z","steps":["trace[487379117] 'agreement among raft nodes before linearized reading' (duration: 127.592888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:28.189991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.11463ms","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-11T01:51:28.19002Z","caller":"traceutil/trace.go:171","msg":"trace[576961073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"206.173022ms","start":"2026-02-11T01:51:27.983842Z","end":"2026-02-11T01:51:28.190015Z","steps":["trace[576961073] 'agreement among raft nodes before linearized reading' (duration: 206.11077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:28.19016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.862895ms","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-11T01:51:28.190176Z","caller":"traceutil/trace.go:171","msg":"trace[956598963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"629.881825ms","start":"2026-02-11T01:51:27.56029Z","end":"2026-02-11T01:51:28.190172Z","steps":["trace[956598963] 'agreement among raft nodes before linearized reading' (duration: 629.836404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:28.190191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.560195Z","time spent":"629.992868ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:28.190641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.373169ms","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-11T01:51:28.190664Z","caller":"traceutil/trace.go:171","msg":"trace[390218643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"668.40928ms","start":"2026-02-11T01:51:27.522247Z","end":"2026-02-11T01:51:28.190656Z","steps":["trace[390218643] 'agreement among raft nodes before linearized reading' (duration: 667.363306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:28.190682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:27.52224Z","time spent":"668.43792ms","remote":"127.0.0.1:49992","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-11T01:51:30.338692Z","caller":"traceutil/trace.go:171","msg":"trace[354589043] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"208.532836ms","start":"2026-02-11T01:51:30.129541Z","end":"2026-02-11T01:51:30.338073Z","steps":["trace[354589043] 'process raft request' (duration: 208.31006ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:30.34246Z","caller":"traceutil/trace.go:171","msg":"trace[17773856] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:542; }","duration":"139.953302ms","start":"2026-02-11T01:51:30.202466Z","end":"2026-02-11T01:51:30.342419Z","steps":["trace[17773856] 'read index received' (duration: 135.4812ms)","trace[17773856] 'applied index is now lower than readState.Index' (duration: 4.471352ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:30.342598Z","caller":"traceutil/trace.go:171","msg":"trace[1883036601] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"166.381208ms","start":"2026-02-11T01:51:30.176205Z","end":"2026-02-11T01:51:30.342586Z","steps":["trace[1883036601] 'process raft request' (duration: 166.149293ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:30.342657Z","caller":"traceutil/trace.go:171","msg":"trace[1453666014] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"209.240792ms","start":"2026-02-11T01:51:30.133381Z","end":"2026-02-11T01:51:30.342622Z","steps":["trace[1453666014] 'process raft request' (duration: 208.868553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:30.34308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.576166ms","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-11T01:51:30.343124Z","caller":"traceutil/trace.go:171","msg":"trace[748529206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"140.672928ms","start":"2026-02-11T01:51:30.202438Z","end":"2026-02-11T01:51:30.343111Z","steps":["trace[748529206] 'agreement among raft nodes before linearized reading' (duration: 140.464273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:30.343377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.073993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:51:30.34343Z","caller":"traceutil/trace.go:171","msg":"trace[640093378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"136.145454ms","start":"2026-02-11T01:51:30.207267Z","end":"2026-02-11T01:51:30.343412Z","steps":["trace[640093378] 'agreement among raft nodes before linearized reading' (duration: 135.96711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:30.343738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.388445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-11T01:51:30.343641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.981576ms","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-11T01:51:30.343786Z","caller":"traceutil/trace.go:171","msg":"trace[1630836018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"131.446956ms","start":"2026-02-11T01:51:30.212327Z","end":"2026-02-11T01:51:30.343773Z","steps":["trace[1630836018] 'agreement among raft nodes before linearized reading' (duration: 131.298403ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:30.343808Z","caller":"traceutil/trace.go:171","msg":"trace[648297801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"131.15368ms","start":"2026-02-11T01:51:30.21264Z","end":"2026-02-11T01:51:30.343794Z","steps":["trace[648297801] 'agreement among raft nodes before linearized reading' (duration: 130.902684ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:32.192335Z","caller":"traceutil/trace.go:171","msg":"trace[293291466] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"188.707101ms","start":"2026-02-11T01:51:32.003585Z","end":"2026-02-11T01:51:32.192292Z","steps":["trace[293291466] 'process raft request' (duration: 188.594059ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:32.362392Z","caller":"traceutil/trace.go:171","msg":"trace[881609903] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"154.352882ms","start":"2026-02-11T01:51:32.208014Z","end":"2026-02-11T01:51:32.362367Z","steps":["trace[881609903] 'process raft request' (duration: 93.67324ms)","trace[881609903] 'compare' (duration: 60.58275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:32.700814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.230116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:32.701187Z","caller":"traceutil/trace.go:171","msg":"trace[259858052] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"126.673007ms","start":"2026-02-11T01:51:32.574493Z","end":"2026-02-11T01:51:32.701166Z","steps":["trace[259858052] 'read index received' (duration: 35.141µs)","trace[259858052] 'applied index is now lower than readState.Index' (duration: 126.636916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:32.701336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.835331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-11T01:51:32.701365Z","caller":"traceutil/trace.go:171","msg":"trace[1690677244] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:564; }","duration":"126.900102ms","start":"2026-02-11T01:51:32.574454Z","end":"2026-02-11T01:51:32.701354Z","steps":["trace[1690677244] 'agreement among raft nodes before linearized reading' (duration: 126.768859ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:32.701626Z","caller":"traceutil/trace.go:171","msg":"trace[959531430] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"262.691617ms","start":"2026-02-11T01:51:32.438922Z","end":"2026-02-11T01:51:32.701614Z","steps":["trace[959531430] 'process raft request' (duration: 81.589441ms)","trace[959531430] 'compare' (duration: 180.132154ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:32.701831Z","caller":"traceutil/trace.go:171","msg":"trace[1871392843] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"261.48987ms","start":"2026-02-11T01:51:32.440331Z","end":"2026-02-11T01:51:32.701821Z","steps":["trace[1871392843] 'process raft request' (duration: 260.760603ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:32.896069Z","caller":"traceutil/trace.go:171","msg":"trace[1187406342] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"118.424977ms","start":"2026-02-11T01:51:32.77762Z","end":"2026-02-11T01:51:32.896045Z","steps":["trace[1187406342] 'read index received' (duration: 51.021681ms)","trace[1187406342] 'applied index is now lower than readState.Index' (duration: 67.402346ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:32.89628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.645193ms","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-11T01:51:32.896312Z","caller":"traceutil/trace.go:171","msg":"trace[1454637934] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:566; }","duration":"118.715174ms","start":"2026-02-11T01:51:32.777586Z","end":"2026-02-11T01:51:32.896301Z","steps":["trace[1454637934] 'agreement among raft nodes before linearized reading' (duration: 118.54657ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:32.897661Z","caller":"traceutil/trace.go:171","msg":"trace[1487139902] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"129.340438ms","start":"2026-02-11T01:51:32.768286Z","end":"2026-02-11T01:51:32.897627Z","steps":["trace[1487139902] 'process raft request' (duration: 60.404807ms)","trace[1487139902] 'compare' (duration: 67.034707ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:33.188441Z","caller":"traceutil/trace.go:171","msg":"trace[2102261752] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"278.046211ms","start":"2026-02-11T01:51:32.910374Z","end":"2026-02-11T01:51:33.18842Z","steps":["trace[2102261752] 'process raft request' (duration: 243.515218ms)","trace[2102261752] 'compare' (duration: 33.950859ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:33.188695Z","caller":"traceutil/trace.go:171","msg":"trace[1001451476] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"212.061767ms","start":"2026-02-11T01:51:32.976623Z","end":"2026-02-11T01:51:33.188685Z","steps":["trace[1001451476] 'process raft request' (duration: 211.717249ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:33.792165Z","caller":"traceutil/trace.go:171","msg":"trace[20287279] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"166.250755ms","start":"2026-02-11T01:51:33.625894Z","end":"2026-02-11T01:51:33.792145Z","steps":["trace[20287279] 'process raft request' (duration: 162.987181ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:34.704404Z","caller":"traceutil/trace.go:171","msg":"trace[1357795027] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"130.827723ms","start":"2026-02-11T01:51:34.57356Z","end":"2026-02-11T01:51:34.704387Z","steps":["trace[1357795027] 'read index received' (duration: 129.456271ms)","trace[1357795027] 'applied index is now lower than readState.Index' (duration: 1.370632ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:34.704768Z","caller":"traceutil/trace.go:171","msg":"trace[926952524] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"204.38329ms","start":"2026-02-11T01:51:34.500376Z","end":"2026-02-11T01:51:34.704759Z","steps":["trace[926952524] 'process raft request' (duration: 202.685731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:34.705037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.110259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-11T01:51:34.705062Z","caller":"traceutil/trace.go:171","msg":"trace[861880087] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:585; }","duration":"131.536639ms","start":"2026-02-11T01:51:34.573518Z","end":"2026-02-11T01:51:34.705055Z","steps":["trace[861880087] 'agreement among raft nodes before linearized reading' (duration: 130.936585ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:38.476148Z","caller":"traceutil/trace.go:171","msg":"trace[923861507] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"111.835295ms","start":"2026-02-11T01:51:38.364298Z","end":"2026-02-11T01:51:38.476134Z","steps":["trace[923861507] 'read index received' (duration: 9.141707ms)","trace[923861507] 'applied index is now lower than readState.Index' (duration: 102.692788ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:38.476165Z","caller":"traceutil/trace.go:171","msg":"trace[1904129744] transaction","detail":"{read_only:false; number_of_response:0; response_revision:603; }","duration":"169.735124ms","start":"2026-02-11T01:51:38.306402Z","end":"2026-02-11T01:51:38.476138Z","steps":["trace[1904129744] 'process raft request' (duration: 126.626622ms)","trace[1904129744] 'compare' (duration: 42.941927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:38.476641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.325519ms","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-11T01:51:38.4767Z","caller":"traceutil/trace.go:171","msg":"trace[809475862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:604; }","duration":"112.529035ms","start":"2026-02-11T01:51:38.36416Z","end":"2026-02-11T01:51:38.476689Z","steps":["trace[809475862] 'agreement among raft nodes before linearized reading' (duration: 112.36526ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:38.476345Z","caller":"traceutil/trace.go:171","msg":"trace[1598932694] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"112.236417ms","start":"2026-02-11T01:51:38.364094Z","end":"2026-02-11T01:51:38.476331Z","steps":["trace[1598932694] 'process raft request' (duration: 111.951659ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:38.48708Z","caller":"traceutil/trace.go:171","msg":"trace[1031037135] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"107.836873ms","start":"2026-02-11T01:51:38.379233Z","end":"2026-02-11T01:51:38.487069Z","steps":["trace[1031037135] 'process raft request' (duration: 107.74178ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:41.922636Z","caller":"traceutil/trace.go:171","msg":"trace[712638226] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"785.78968ms","start":"2026-02-11T01:51:41.136823Z","end":"2026-02-11T01:51:41.922613Z","steps":["trace[712638226] 'process raft request' (duration: 785.690107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:41.922729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:41.136808Z","time spent":"785.870303ms","remote":"127.0.0.1:50194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:41.998264Z","caller":"traceutil/trace.go:171","msg":"trace[1463102763] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"579.798087ms","start":"2026-02-11T01:51:41.418443Z","end":"2026-02-11T01:51:41.998241Z","steps":["trace[1463102763] 'process raft request' (duration: 579.774616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:41.998362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:41.418415Z","time spent":"579.88984ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:41.998828Z","caller":"traceutil/trace.go:171","msg":"trace[379228917] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"841.558948ms","start":"2026-02-11T01:51:41.157261Z","end":"2026-02-11T01:51:41.99882Z","steps":["trace[379228917] 'process raft request' (duration: 835.92579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:41.998877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:41.157249Z","time spent":"841.60502ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3798,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:41.999022Z","caller":"traceutil/trace.go:171","msg":"trace[131838810] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"841.689733ms","start":"2026-02-11T01:51:41.157324Z","end":"2026-02-11T01:51:41.999014Z","steps":["trace[131838810] 'process raft request' (duration: 840.825348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:41.999067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:41.157321Z","time spent":"841.723433ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:41.999224Z","caller":"traceutil/trace.go:171","msg":"trace[126739678] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"841.794425ms","start":"2026-02-11T01:51:41.15742Z","end":"2026-02-11T01:51:41.999214Z","steps":["trace[126739678] 'process raft request' (duration: 840.754946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:41.999266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:41.157416Z","time spent":"841.829336ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:41.999569Z","caller":"traceutil/trace.go:171","msg":"trace[651016475] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"840.206931ms","start":"2026-02-11T01:51:41.159353Z","end":"2026-02-11T01:51:41.99956Z","steps":["trace[651016475] 'process raft request' (duration: 838.841283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:41.999615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:41.159346Z","time spent":"840.246402ms","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4438,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:42.821128Z","caller":"traceutil/trace.go:171","msg":"trace[471267493] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"223.188677ms","start":"2026-02-11T01:51:42.597923Z","end":"2026-02-11T01:51:42.821112Z","steps":["trace[471267493] 'process raft request' (duration: 223.073814ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:42.843259Z","caller":"traceutil/trace.go:171","msg":"trace[463276866] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:655; }","duration":"236.520322ms","start":"2026-02-11T01:51:42.606721Z","end":"2026-02-11T01:51:42.843242Z","steps":["trace[463276866] 'read index received' (duration: 214.327008ms)","trace[463276866] 'applied index is now lower than readState.Index' (duration: 22.192534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:42.843426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.684907ms","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-11T01:51:42.84346Z","caller":"traceutil/trace.go:171","msg":"trace[2119866410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"236.745578ms","start":"2026-02-11T01:51:42.60671Z","end":"2026-02-11T01:51:42.843455Z","steps":["trace[2119866410] 'agreement among raft nodes before linearized reading' (duration: 236.620165ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:42.843677Z","caller":"traceutil/trace.go:171","msg":"trace[198984861] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"242.84883ms","start":"2026-02-11T01:51:42.600821Z","end":"2026-02-11T01:51:42.843669Z","steps":["trace[198984861] 'process raft request' (duration: 242.312335ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:42.843778Z","caller":"traceutil/trace.go:171","msg":"trace[1658352753] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"242.893001ms","start":"2026-02-11T01:51:42.60088Z","end":"2026-02-11T01:51:42.843773Z","steps":["trace[1658352753] 'process raft request' (duration: 242.338016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:43.144776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.48172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:43.145048Z","caller":"traceutil/trace.go:171","msg":"trace[457872135] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"240.963357ms","start":"2026-02-11T01:51:42.904066Z","end":"2026-02-11T01:51:43.14503Z","steps":["trace[457872135] 'process raft request' (duration: 62.173398ms)","trace[457872135] 'compare' (duration: 178.423668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:43.465718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.508364ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T01:51:45.276658Z","caller":"traceutil/trace.go:171","msg":"trace[1442939697] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"125.954932ms","start":"2026-02-11T01:51:45.150689Z","end":"2026-02-11T01:51:45.276644Z","steps":["trace[1442939697] 'process raft request' (duration: 125.611153ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:45.522634Z","caller":"traceutil/trace.go:171","msg":"trace[1060064355] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"149.840974ms","start":"2026-02-11T01:51:45.372774Z","end":"2026-02-11T01:51:45.522615Z","steps":["trace[1060064355] 'process raft request' (duration: 95.891397ms)","trace[1060064355] 'compare' (duration: 53.838174ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:45.871988Z","caller":"traceutil/trace.go:171","msg":"trace[432003310] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"287.530027ms","start":"2026-02-11T01:51:45.584445Z","end":"2026-02-11T01:51:45.871975Z","steps":["trace[432003310] 'process raft request' (duration: 287.482875ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:45.87266Z","caller":"traceutil/trace.go:171","msg":"trace[1245491637] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"287.28558ms","start":"2026-02-11T01:51:45.584366Z","end":"2026-02-11T01:51:45.871651Z","steps":["trace[1245491637] 'process raft request' (duration: 191.108055ms)","trace[1245491637] 'compare' (duration: 95.913187ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:51.220491Z","caller":"traceutil/trace.go:171","msg":"trace[1182238618] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"251.72244ms","start":"2026-02-11T01:51:50.968747Z","end":"2026-02-11T01:51:51.220469Z","steps":["trace[1182238618] 'process raft request' (duration: 251.614627ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:51.221021Z","caller":"traceutil/trace.go:171","msg":"trace[1147639782] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"237.858549ms","start":"2026-02-11T01:51:50.983148Z","end":"2026-02-11T01:51:51.221007Z","steps":["trace[1147639782] 'read index received' (duration: 237.147859ms)","trace[1147639782] 'applied index is now lower than readState.Index' (duration: 707.4µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:51.221176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.006214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-11T01:51:51.221927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.695231ms","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-11T01:51:51.221964Z","caller":"traceutil/trace.go:171","msg":"trace[1837275311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:693; }","duration":"213.751362ms","start":"2026-02-11T01:51:51.008201Z","end":"2026-02-11T01:51:51.221952Z","steps":["trace[1837275311] 'agreement among raft nodes before linearized reading' (duration: 213.642909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:51.222128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.816233ms","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-11T01:51:51.222153Z","caller":"traceutil/trace.go:171","msg":"trace[2075408945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"208.840774ms","start":"2026-02-11T01:51:51.013305Z","end":"2026-02-11T01:51:51.222145Z","steps":["trace[2075408945] 'agreement among raft nodes before linearized reading' (duration: 208.773012ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:51.2225Z","caller":"traceutil/trace.go:171","msg":"trace[499243354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"238.066655ms","start":"2026-02-11T01:51:50.98313Z","end":"2026-02-11T01:51:51.221196Z","steps":["trace[499243354] 'agreement among raft nodes before linearized reading' (duration: 237.946872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:51.871983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242013888,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:51:52.557867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.202148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:52.557967Z","caller":"traceutil/trace.go:171","msg":"trace[266048466] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"1.331586851s","start":"2026-02-11T01:51:51.226363Z","end":"2026-02-11T01:51:52.557949Z","steps":["trace[266048466] 'process raft request' (duration: 895.207428ms)","trace[266048466] 'compare' (duration: 435.964721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:52.558049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:51.226347Z","time spent":"1.331673293s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:53.93457Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.396120796s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T01:51:54.059325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000299446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:51:54.059414Z","caller":"traceutil/trace.go:171","msg":"trace[1567069491] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00043394s","start":"2026-02-11T01:51:52.05896Z","end":"2026-02-11T01:51:54.059394Z","steps":["trace[1567069491] 'agreement among raft nodes before linearized reading' (duration: 2.000321267s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:54.059483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:52.058947Z","time spent":"2.000510533s","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:51:54.309011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.204063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:54.309159Z","caller":"traceutil/trace.go:171","msg":"trace[1036852179] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:716; }","duration":"2.937562716s","start":"2026-02-11T01:51:51.371575Z","end":"2026-02-11T01:51:54.309138Z","steps":["trace[1036852179] 'read index received' (duration: 750.003753ms)","trace[1036852179] 'applied index is now lower than readState.Index' (duration: 2.187558193s)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:54.3093Z","caller":"traceutil/trace.go:171","msg":"trace[1259559544] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"3.080408054s","start":"2026-02-11T01:51:51.228883Z","end":"2026-02-11T01:51:54.309291Z","steps":["trace[1259559544] 'process raft request' (duration: 2.705837241s)","trace[1259559544] 'compare' (duration: 373.938735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:54.309348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:51.228875Z","time spent":"3.080450355s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:54.309467Z","caller":"traceutil/trace.go:171","msg":"trace[1585239447] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"3.080428205s","start":"2026-02-11T01:51:51.229032Z","end":"2026-02-11T01:51:54.30946Z","steps":["trace[1585239447] 'process raft request' (duration: 3.080042424s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:54.309503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:51.229025Z","time spent":"3.080461276s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:54.309639Z","caller":"traceutil/trace.go:171","msg":"trace[1619672513] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"2.768856572s","start":"2026-02-11T01:51:51.540777Z","end":"2026-02-11T01:51:54.309633Z","steps":["trace[1619672513] 'process raft request' (duration: 2.768331327s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:54.309677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:51.540759Z","time spent":"2.768900743s","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:54.309818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.938245926s","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-11T01:51:54.309841Z","caller":"traceutil/trace.go:171","msg":"trace[1873084882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:697; }","duration":"2.938268216s","start":"2026-02-11T01:51:51.371567Z","end":"2026-02-11T01:51:54.309835Z","steps":["trace[1873084882] 'agreement among raft nodes before linearized reading' (duration: 2.938194364s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:54.309872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:51.371522Z","time spent":"2.938344458s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:51:54.756385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.790121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:54.756982Z","caller":"traceutil/trace.go:171","msg":"trace[1300024562] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"2.572438797s","start":"2026-02-11T01:51:52.184505Z","end":"2026-02-11T01:51:54.756944Z","steps":["trace[1300024562] 'process raft request' (duration: 2.352011588s)","trace[1300024562] 'compare' (duration: 219.684219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:54.757103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:52.184482Z","time spent":"2.572554501s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:54.757204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:52.314031Z","time spent":"2.443169362s","remote":"127.0.0.1:49824","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T01:51:55.402498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.870065ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T01:51:55.402642Z","caller":"traceutil/trace.go:171","msg":"trace[1606247453] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:720; }","duration":"1.093446823s","start":"2026-02-11T01:51:54.309182Z","end":"2026-02-11T01:51:55.402629Z","steps":["trace[1606247453] 'read index received' (duration: 227.341584ms)","trace[1606247453] 'applied index is now lower than readState.Index' (duration: 866.104679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:55.403002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342926209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.403023Z","caller":"traceutil/trace.go:171","msg":"trace[56072126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"1.342982441s","start":"2026-02-11T01:51:54.060034Z","end":"2026-02-11T01:51:55.403016Z","steps":["trace[56072126] 'agreement among raft nodes before linearized reading' (duration: 1.34294129s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.403043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:54.060018Z","time spent":"1.343019902s","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:51:55.403211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.179356982s","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-11T01:51:55.403228Z","caller":"traceutil/trace.go:171","msg":"trace[141994491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:698; }","duration":"2.179405444s","start":"2026-02-11T01:51:53.223817Z","end":"2026-02-11T01:51:55.403223Z","steps":["trace[141994491] 'agreement among raft nodes before linearized reading' (duration: 2.179341612s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.403242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:53.223802Z","time spent":"2.179436584s","remote":"127.0.0.1:49992","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-11T01:51:55.403702Z","caller":"traceutil/trace.go:171","msg":"trace[1825085244] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"1.077741901s","start":"2026-02-11T01:51:54.325953Z","end":"2026-02-11T01:51:55.403694Z","steps":["trace[1825085244] 'process raft request' (duration: 1.076613449s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.403759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:54.325934Z","time spent":"1.077785913s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:55.815565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.835723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:55.815659Z","caller":"traceutil/trace.go:171","msg":"trace[778301847] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"406.489112ms","start":"2026-02-11T01:51:55.40916Z","end":"2026-02-11T01:51:55.815649Z","steps":["trace[778301847] 'process raft request' (duration: 406.461521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.815698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.409147Z","time spent":"406.530524ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:55.815786Z","caller":"traceutil/trace.go:171","msg":"trace[699482538] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"1.057614135s","start":"2026-02-11T01:51:54.758167Z","end":"2026-02-11T01:51:55.815781Z","steps":["trace[699482538] 'process raft request' (duration: 645.522625ms)","trace[699482538] 'compare' (duration: 411.592826ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:55.815808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:54.758147Z","time spent":"1.057649466s","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:51:55.815873Z","caller":"traceutil/trace.go:171","msg":"trace[1812595528] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:723; }","duration":"413.208591ms","start":"2026-02-11T01:51:55.402659Z","end":"2026-02-11T01:51:55.815867Z","steps":["trace[1812595528] 'read index received' (duration: 659.628µs)","trace[1812595528] 'applied index is now lower than readState.Index' (duration: 412.548403ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:55.81598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.157933ms","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-11T01:51:55.815994Z","caller":"traceutil/trace.go:171","msg":"trace[1337230761] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:701; }","duration":"470.201505ms","start":"2026-02-11T01:51:55.345788Z","end":"2026-02-11T01:51:55.81599Z","steps":["trace[1337230761] 'agreement among raft nodes before linearized reading' (duration: 470.174294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.345774Z","time spent":"470.230985ms","remote":"127.0.0.1:50088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:51:55.816081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.092979ms","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-11T01:51:55.816093Z","caller":"traceutil/trace.go:171","msg":"trace[1378809218] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:701; }","duration":"663.13743ms","start":"2026-02-11T01:51:55.152952Z","end":"2026-02-11T01:51:55.816089Z","steps":["trace[1378809218] 'agreement among raft nodes before linearized reading' (duration: 663.1141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.152938Z","time spent":"663.163802ms","remote":"127.0.0.1:38496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-11T01:51:55.81617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.195033ms","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-11T01:51:55.816182Z","caller":"traceutil/trace.go:171","msg":"trace[200311047] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:701; }","duration":"663.222263ms","start":"2026-02-11T01:51:55.152956Z","end":"2026-02-11T01:51:55.816178Z","steps":["trace[200311047] 'agreement among raft nodes before linearized reading' (duration: 663.202632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.152949Z","time spent":"663.241313ms","remote":"127.0.0.1:38496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:51:55.816246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.303635ms","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-11T01:51:55.816257Z","caller":"traceutil/trace.go:171","msg":"trace[1094600078] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:701; }","duration":"663.336716ms","start":"2026-02-11T01:51:55.152917Z","end":"2026-02-11T01:51:55.816254Z","steps":["trace[1094600078] 'agreement among raft nodes before linearized reading' (duration: 663.319796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.152898Z","time spent":"663.368557ms","remote":"127.0.0.1:38496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-11T01:51:55.816359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24197101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:51:55.81637Z","caller":"traceutil/trace.go:171","msg":"trace[787735250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:701; }","duration":"1.242009361s","start":"2026-02-11T01:51:54.574357Z","end":"2026-02-11T01:51:55.816367Z","steps":["trace[787735250] 'agreement among raft nodes before linearized reading' (duration: 1.24195555s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:54.574343Z","time spent":"1.242036212s","remote":"127.0.0.1:49992","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-11T01:51:55.816439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.602049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.816449Z","caller":"traceutil/trace.go:171","msg":"trace[209611619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"404.63659ms","start":"2026-02-11T01:51:55.411809Z","end":"2026-02-11T01:51:55.816446Z","steps":["trace[209611619] 'agreement among raft nodes before linearized reading' (duration: 404.61947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.411795Z","time spent":"404.662631ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:51:55.816595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.438332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.816609Z","caller":"traceutil/trace.go:171","msg":"trace[141526452] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"219.459073ms","start":"2026-02-11T01:51:55.597146Z","end":"2026-02-11T01:51:55.816605Z","steps":["trace[141526452] 'agreement among raft nodes before linearized reading' (duration: 219.437882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.529855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.816684Z","caller":"traceutil/trace.go:171","msg":"trace[1620640031] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"219.542065ms","start":"2026-02-11T01:51:55.597138Z","end":"2026-02-11T01:51:55.81668Z","steps":["trace[1620640031] 'agreement among raft nodes before linearized reading' (duration: 219.524335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.631118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.816749Z","caller":"traceutil/trace.go:171","msg":"trace[83701985] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"219.660138ms","start":"2026-02-11T01:51:55.597086Z","end":"2026-02-11T01:51:55.816746Z","steps":["trace[83701985] 'agreement among raft nodes before linearized reading' (duration: 219.640157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.8168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.485325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.816811Z","caller":"traceutil/trace.go:171","msg":"trace[501257848] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"223.509236ms","start":"2026-02-11T01:51:55.593298Z","end":"2026-02-11T01:51:55.816808Z","steps":["trace[501257848] 'agreement among raft nodes before linearized reading' (duration: 223.490855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.766544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.816884Z","caller":"traceutil/trace.go:171","msg":"trace[1518206261] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"228.778965ms","start":"2026-02-11T01:51:55.588102Z","end":"2026-02-11T01:51:55.816881Z","steps":["trace[1518206261] 'agreement among raft nodes before linearized reading' (duration: 228.761694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.816932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.850586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.816943Z","caller":"traceutil/trace.go:171","msg":"trace[1023646146] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"228.875917ms","start":"2026-02-11T01:51:55.588064Z","end":"2026-02-11T01:51:55.81694Z","steps":["trace[1023646146] 'agreement among raft nodes before linearized reading' (duration: 228.858377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.817058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.138268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.817074Z","caller":"traceutil/trace.go:171","msg":"trace[1748255874] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"238.182159ms","start":"2026-02-11T01:51:55.578888Z","end":"2026-02-11T01:51:55.81707Z","steps":["trace[1748255874] 'agreement among raft nodes before linearized reading' (duration: 238.156698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.817175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.282752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.817189Z","caller":"traceutil/trace.go:171","msg":"trace[1058813428] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"238.306272ms","start":"2026-02-11T01:51:55.578879Z","end":"2026-02-11T01:51:55.817185Z","steps":["trace[1058813428] 'agreement among raft nodes before linearized reading' (duration: 238.285452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:55.81724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.370314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:51:55.817253Z","caller":"traceutil/trace.go:171","msg":"trace[1077400998] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"238.400295ms","start":"2026-02-11T01:51:55.578848Z","end":"2026-02-11T01:51:55.817248Z","steps":["trace[1077400998] 'agreement among raft nodes before linearized reading' (duration: 238.383525ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:56.251151Z","caller":"traceutil/trace.go:171","msg":"trace[781905295] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"426.983389ms","start":"2026-02-11T01:51:55.824154Z","end":"2026-02-11T01:51:56.251137Z","steps":["trace[781905295] 'process raft request' (duration: 426.30921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:56.251555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.824145Z","time spent":"427.04562ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:51:56.529657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.746774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:51:56.529735Z","caller":"traceutil/trace.go:171","msg":"trace[863759231] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"206.200679ms","start":"2026-02-11T01:51:56.323523Z","end":"2026-02-11T01:51:56.529723Z","steps":["trace[863759231] 'read index received' (duration: 41.374843ms)","trace[863759231] 'applied index is now lower than readState.Index' (duration: 164.825056ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:51:56.529798Z","caller":"traceutil/trace.go:171","msg":"trace[636330381] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"704.900095ms","start":"2026-02-11T01:51:55.82489Z","end":"2026-02-11T01:51:56.52979Z","steps":["trace[636330381] 'process raft request' (duration: 539.974396ms)","trace[636330381] 'compare' (duration: 164.633771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:56.52983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:55.824885Z","time spent":"704.929386ms","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:51:56.529967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.249185ms","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-11T01:51:56.53002Z","caller":"traceutil/trace.go:171","msg":"trace[721307851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:703; }","duration":"204.335857ms","start":"2026-02-11T01:51:56.325669Z","end":"2026-02-11T01:51:56.530005Z","steps":["trace[721307851] 'agreement among raft nodes before linearized reading' (duration: 204.151252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:56.530138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.609011ms","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-11T01:51:56.530163Z","caller":"traceutil/trace.go:171","msg":"trace[423462377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"206.665422ms","start":"2026-02-11T01:51:56.323488Z","end":"2026-02-11T01:51:56.530154Z","steps":["trace[423462377] 'agreement among raft nodes before linearized reading' (duration: 206.569889ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:51:56.884585Z","caller":"traceutil/trace.go:171","msg":"trace[1259109826] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"347.942986ms","start":"2026-02-11T01:51:56.536626Z","end":"2026-02-11T01:51:56.884569Z","steps":["trace[1259109826] 'process raft request' (duration: 347.909035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:56.884689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:56.536618Z","time spent":"348.017998ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:56.884896Z","caller":"traceutil/trace.go:171","msg":"trace[585587390] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"348.961554ms","start":"2026-02-11T01:51:56.535926Z","end":"2026-02-11T01:51:56.884888Z","steps":["trace[585587390] 'process raft request' (duration: 309.01681ms)","trace[585587390] 'compare' (duration: 39.304266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:51:56.884939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:51:56.535911Z","time spent":"349.008886ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:51:57.679275Z","caller":"traceutil/trace.go:171","msg":"trace[1062416388] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"266.303818ms","start":"2026-02-11T01:51:57.412783Z","end":"2026-02-11T01:51:57.679087Z","steps":["trace[1062416388] 'process raft request' (duration: 266.170374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:51:57.988174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.780437ms","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-11T01:51:57.988233Z","caller":"traceutil/trace.go:171","msg":"trace[2039757034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:706; }","duration":"163.867818ms","start":"2026-02-11T01:51:57.824353Z","end":"2026-02-11T01:51:57.988221Z","steps":["trace[2039757034] 'range keys from in-memory index tree' (duration: 163.669333ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:08.745902Z","caller":"traceutil/trace.go:171","msg":"trace[1733028916] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"295.487931ms","start":"2026-02-11T01:52:08.450371Z","end":"2026-02-11T01:52:08.745859Z","steps":["trace[1733028916] 'process raft request' (duration: 295.244234ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:10.365226Z","caller":"traceutil/trace.go:171","msg":"trace[982965098] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"306.190421ms","start":"2026-02-11T01:52:10.059015Z","end":"2026-02-11T01:52:10.365205Z","steps":["trace[982965098] 'read index received' (duration: 306.015256ms)","trace[982965098] 'applied index is now lower than readState.Index' (duration: 174.235µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:10.365453Z","caller":"traceutil/trace.go:171","msg":"trace[1536528236] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"585.078485ms","start":"2026-02-11T01:52:09.780364Z","end":"2026-02-11T01:52:10.365443Z","steps":["trace[1536528236] 'process raft request' (duration: 584.726115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.365547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:09.780346Z","time spent":"585.134877ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:10.365718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.701275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:10.365748Z","caller":"traceutil/trace.go:171","msg":"trace[1117493715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"306.762447ms","start":"2026-02-11T01:52:10.058976Z","end":"2026-02-11T01:52:10.365739Z","steps":["trace[1117493715] 'agreement among raft nodes before linearized reading' (duration: 306.714556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:10.365773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.058961Z","time spent":"306.806889ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:10.366026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.033855ms","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-11T01:52:10.366056Z","caller":"traceutil/trace.go:171","msg":"trace[456932231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"259.106527ms","start":"2026-02-11T01:52:10.106939Z","end":"2026-02-11T01:52:10.366046Z","steps":["trace[456932231] 'agreement among raft nodes before linearized reading' (duration: 258.993943ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:11.382582Z","caller":"traceutil/trace.go:171","msg":"trace[1832197324] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"380.45913ms","start":"2026-02-11T01:52:11.002105Z","end":"2026-02-11T01:52:11.382564Z","steps":["trace[1832197324] 'read index received' (duration: 380.371977ms)","trace[1832197324] 'applied index is now lower than readState.Index' (duration: 86.353µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:11.38267Z","caller":"traceutil/trace.go:171","msg":"trace[854878978] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"397.630212ms","start":"2026-02-11T01:52:10.985034Z","end":"2026-02-11T01:52:11.382664Z","steps":["trace[854878978] 'process raft request' (duration: 397.429777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:11.382746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:10.985017Z","time spent":"397.670364ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:11.382897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.131628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:11.382986Z","caller":"traceutil/trace.go:171","msg":"trace[1232611901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"323.257711ms","start":"2026-02-11T01:52:11.059688Z","end":"2026-02-11T01:52:11.382946Z","steps":["trace[1232611901] 'agreement among raft nodes before linearized reading' (duration: 323.135898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:11.383013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.913503ms","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-11T01:52:11.383038Z","caller":"traceutil/trace.go:171","msg":"trace[683568190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:749; }","duration":"380.995065ms","start":"2026-02-11T01:52:11.002036Z","end":"2026-02-11T01:52:11.383031Z","steps":["trace[683568190] 'agreement among raft nodes before linearized reading' (duration: 380.908472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:11.383025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:11.059642Z","time spent":"323.375134ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:11.383059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:11.002015Z","time spent":"381.039076ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:11.902656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.943672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:11.902836Z","caller":"traceutil/trace.go:171","msg":"trace[1151708453] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"512.695388ms","start":"2026-02-11T01:52:11.39009Z","end":"2026-02-11T01:52:11.902785Z","steps":["trace[1151708453] 'process raft request' (duration: 200.433088ms)","trace[1151708453] 'compare' (duration: 311.616612ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:11.903757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:11.390078Z","time spent":"512.930565ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:12.836928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.824013ms","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-11T01:52:12.837205Z","caller":"traceutil/trace.go:171","msg":"trace[905578294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"364.131691ms","start":"2026-02-11T01:52:12.473058Z","end":"2026-02-11T01:52:12.83719Z","steps":["trace[905578294] 'range keys from in-memory index tree' (duration: 363.745021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:12.83736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.473044Z","time spent":"364.305427ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:52:12.837501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.777366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:12.837602Z","caller":"traceutil/trace.go:171","msg":"trace[1116951500] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:750; }","duration":"676.228608ms","start":"2026-02-11T01:52:12.161352Z","end":"2026-02-11T01:52:12.837581Z","steps":["trace[1116951500] 'count revisions from in-memory index tree' (duration: 675.706214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:12.837684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.161334Z","time spent":"676.334212ms","remote":"127.0.0.1:50010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:12.836858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.640064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:12.838532Z","caller":"traceutil/trace.go:171","msg":"trace[92732585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"772.357662ms","start":"2026-02-11T01:52:12.066161Z","end":"2026-02-11T01:52:12.838518Z","steps":["trace[92732585] 'range keys from in-memory index tree' (duration: 770.535471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:12.838642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.066146Z","time spent":"772.483376ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:52:12.838977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.662556ms","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-11T01:52:12.839047Z","caller":"traceutil/trace.go:171","msg":"trace[738328857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"162.764838ms","start":"2026-02-11T01:52:12.676274Z","end":"2026-02-11T01:52:12.839039Z","steps":["trace[738328857] 'range keys from in-memory index tree' (duration: 162.550412ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:13.30034Z","caller":"traceutil/trace.go:171","msg":"trace[336725331] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"453.778142ms","start":"2026-02-11T01:52:12.846549Z","end":"2026-02-11T01:52:13.300328Z","steps":["trace[336725331] 'process raft request' (duration: 453.70709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:13.300425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.846535Z","time spent":"453.846635ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:13.888269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014044,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:52:14.879907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.702725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:14.880051Z","caller":"traceutil/trace.go:171","msg":"trace[1894344122] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"2.031754312s","start":"2026-02-11T01:52:12.848282Z","end":"2026-02-11T01:52:14.880036Z","steps":["trace[1894344122] 'process raft request' (duration: 2.031702961s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:14.880118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.848277Z","time spent":"2.031805604s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:14.8804Z","caller":"traceutil/trace.go:171","msg":"trace[1954567339] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"2.032147803s","start":"2026-02-11T01:52:12.84824Z","end":"2026-02-11T01:52:14.880388Z","steps":["trace[1954567339] 'process raft request' (duration: 1.153896543s)","trace[1954567339] 'compare' (duration: 877.361685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:14.880453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:12.848233Z","time spent":"2.032193554s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:16.058633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001030205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:52:16.058971Z","caller":"traceutil/trace.go:171","msg":"trace[339683669] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001408534s","start":"2026-02-11T01:52:14.057543Z","end":"2026-02-11T01:52:16.058952Z","steps":["trace[339683669] 'agreement among raft nodes before linearized reading' (duration: 2.001053866s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.05908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.057529Z","time spent":"2.001534256s","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:52:16 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:52:16.704247Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050067518s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T01:52:16.704386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.820016978s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T01:52:16.704586Z","caller":"traceutil/trace.go:171","msg":"trace[1922435678] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:779; }","duration":"3.31669639s","start":"2026-02-11T01:52:13.387872Z","end":"2026-02-11T01:52:16.704568Z","steps":["trace[1922435678] 'read index received' (duration: 614.274256ms)","trace[1922435678] 'applied index is now lower than readState.Index' (duration: 2.702421214s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:16.705423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.317536109s","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-11T01:52:16.70547Z","caller":"traceutil/trace.go:171","msg":"trace[2081779717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"3.317626801s","start":"2026-02-11T01:52:13.387827Z","end":"2026-02-11T01:52:16.705453Z","steps":["trace[2081779717] 'agreement among raft nodes before linearized reading' (duration: 3.317467067s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.705508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.387807Z","time spent":"3.317692303s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:52:16.70581Z","caller":"traceutil/trace.go:171","msg":"trace[1164936855] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.802322462s","start":"2026-02-11T01:52:14.903472Z","end":"2026-02-11T01:52:16.705794Z","steps":["trace[1164936855] 'process raft request' (duration: 1.801000771s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.705988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.903442Z","time spent":"1.802459686s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:17.021458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.111869516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-11T01:52:17.022421Z","caller":"traceutil/trace.go:171","msg":"trace[2140237241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"3.112877229s","start":"2026-02-11T01:52:13.909533Z","end":"2026-02-11T01:52:17.02241Z","steps":["trace[2140237241] 'agreement among raft nodes before linearized reading' (duration: 2.796254357s)","trace[2140237241] 'range keys from in-memory index tree' (duration: 315.581338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.022891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.909516Z","time spent":"3.11336311s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:17.02182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.082090357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-11T01:52:17.021844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.368177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T01:52:17.021889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159867771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-11T01:52:17.021933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718104868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-11T01:52:17.021951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.853110946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-11T01:52:17.021977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.960349541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:52:17.022218Z","caller":"traceutil/trace.go:171","msg":"trace[1518888193] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"135.964879ms","start":"2026-02-11T01:52:16.886242Z","end":"2026-02-11T01:52:17.022207Z","steps":["trace[1518888193] 'read index received' (duration: 69.144611ms)","trace[1518888193] 'applied index is now lower than readState.Index' (duration: 66.819528ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.022291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.056551ms","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-11T01:52:17.022325Z","caller":"traceutil/trace.go:171","msg":"trace[2019631076] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"308.527465ms","start":"2026-02-11T01:52:16.713788Z","end":"2026-02-11T01:52:17.022315Z","steps":["trace[2019631076] 'process raft request' (duration: 241.680827ms)","trace[2019631076] 'compare' (duration: 66.679684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.024105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:16.713773Z","time spent":"310.287926ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:17.024387Z","caller":"traceutil/trace.go:171","msg":"trace[149398221] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:754; }","duration":"2.855551892s","start":"2026-02-11T01:52:14.168814Z","end":"2026-02-11T01:52:17.024366Z","steps":["trace[149398221] 'agreement among raft nodes before linearized reading' (duration: 2.537004665s)","trace[149398221] 'count revisions from in-memory index tree' (duration: 316.122061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.02445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.168807Z","time spent":"2.855625664s","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:52:17.024623Z","caller":"traceutil/trace.go:171","msg":"trace[1373233627] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:754; }","duration":"2.962989962s","start":"2026-02-11T01:52:14.061623Z","end":"2026-02-11T01:52:17.024613Z","steps":["trace[1373233627] 'agreement among raft nodes before linearized reading' (duration: 2.64420179s)","trace[1373233627] 'count revisions from in-memory index tree' (duration: 316.142441ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.024724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.061604Z","time spent":"2.963108696s","remote":"127.0.0.1:50046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:52:17.024944Z","caller":"traceutil/trace.go:171","msg":"trace[2039617704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"965.49302ms","start":"2026-02-11T01:52:16.059442Z","end":"2026-02-11T01:52:17.024935Z","steps":["trace[2039617704] 'agreement among raft nodes before linearized reading' (duration: 646.357579ms)","trace[2039617704] 'range keys from in-memory index tree' (duration: 316.035689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.025031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:16.059427Z","time spent":"965.576981ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:52:17.025295Z","caller":"traceutil/trace.go:171","msg":"trace[194454110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"139.095151ms","start":"2026-02-11T01:52:16.886192Z","end":"2026-02-11T01:52:17.025287Z","steps":["trace[194454110] 'agreement among raft nodes before linearized reading' (duration: 136.04485ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:17.025464Z","caller":"traceutil/trace.go:171","msg":"trace[146730421] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:754; }","duration":"1.163464675s","start":"2026-02-11T01:52:15.861982Z","end":"2026-02-11T01:52:17.025446Z","steps":["trace[146730421] 'agreement among raft nodes before linearized reading' (duration: 843.824352ms)","trace[146730421] 'count revisions from in-memory index tree' (duration: 316.07019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.025629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:15.861961Z","time spent":"1.163569567s","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:52:17.026061Z","caller":"traceutil/trace.go:171","msg":"trace[2064176568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"1.722193443s","start":"2026-02-11T01:52:15.303768Z","end":"2026-02-11T01:52:17.025961Z","steps":["trace[2064176568] 'agreement among raft nodes before linearized reading' (duration: 1.402044958s)","trace[2064176568] 'range keys from in-memory index tree' (duration: 316.08435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.02616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:15.303749Z","time spent":"1.722397208s","remote":"127.0.0.1:49992","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":"info","ts":"2026-02-11T01:52:17.024908Z","caller":"traceutil/trace.go:171","msg":"trace[1905990138] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:754; }","duration":"3.085194038s","start":"2026-02-11T01:52:13.939698Z","end":"2026-02-11T01:52:17.024892Z","steps":["trace[1905990138] 'agreement among raft nodes before linearized reading' (duration: 2.766131929s)","trace[1905990138] 'count revisions from in-memory index tree' (duration: 315.968727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:17.026456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:13.939689Z","time spent":"3.086742114s","remote":"127.0.0.1:49842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:52:17.026866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.631564ms","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-11T01:52:17.027038Z","caller":"traceutil/trace.go:171","msg":"trace[1624166088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"139.726606ms","start":"2026-02-11T01:52:16.887191Z","end":"2026-02-11T01:52:17.026917Z","steps":["trace[1624166088] 'agreement among raft nodes before linearized reading' (duration: 139.526141ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:21.234584Z","caller":"traceutil/trace.go:171","msg":"trace[962802423] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"129.423518ms","start":"2026-02-11T01:52:21.105147Z","end":"2026-02-11T01:52:21.234571Z","steps":["trace[962802423] 'process raft request' (duration: 129.284374ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:21.235342Z","caller":"traceutil/trace.go:171","msg":"trace[1434825491] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:798; }","duration":"107.380607ms","start":"2026-02-11T01:52:21.127954Z","end":"2026-02-11T01:52:21.235334Z","steps":["trace[1434825491] 'read index received' (duration: 107.376857ms)","trace[1434825491] 'applied index is now lower than readState.Index' (duration: 2.9µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:21.236079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.117244ms","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-11T01:52:21.237286Z","caller":"traceutil/trace.go:171","msg":"trace[1869302371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"109.361423ms","start":"2026-02-11T01:52:21.127916Z","end":"2026-02-11T01:52:21.237278Z","steps":["trace[1869302371] 'agreement among raft nodes before linearized reading' (duration: 107.53626ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:21.387111Z","caller":"traceutil/trace.go:171","msg":"trace[235372982] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"144.697681ms","start":"2026-02-11T01:52:21.242386Z","end":"2026-02-11T01:52:21.387084Z","steps":["trace[235372982] 'process raft request' (duration: 54.926382ms)","trace[235372982] 'compare' (duration: 89.631665ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:21.460047Z","caller":"traceutil/trace.go:171","msg":"trace[963699025] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"215.604593ms","start":"2026-02-11T01:52:21.244432Z","end":"2026-02-11T01:52:21.460036Z","steps":["trace[963699025] 'process raft request' (duration: 215.587303ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:21.461207Z","caller":"traceutil/trace.go:171","msg":"trace[1620100049] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"221.596982ms","start":"2026-02-11T01:52:21.239599Z","end":"2026-02-11T01:52:21.461196Z","steps":["trace[1620100049] 'process raft request' (duration: 220.346583ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:21.461302Z","caller":"traceutil/trace.go:171","msg":"trace[1705276081] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"218.684695ms","start":"2026-02-11T01:52:21.242593Z","end":"2026-02-11T01:52:21.461278Z","steps":["trace[1705276081] 'process raft request' (duration: 217.409395ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:52:57.806637Z","caller":"traceutil/trace.go:171","msg":"trace[231353819] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"198.679668ms","start":"2026-02-11T01:52:57.607945Z","end":"2026-02-11T01:52:57.806625Z","steps":["trace[231353819] 'read index received' (duration: 198.574196ms)","trace[231353819] 'applied index is now lower than readState.Index' (duration: 104.842µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:57.806714Z","caller":"traceutil/trace.go:171","msg":"trace[1817190822] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"309.31535ms","start":"2026-02-11T01:52:57.497393Z","end":"2026-02-11T01:52:57.806708Z","steps":["trace[1817190822] 'process raft request' (duration: 309.130366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.806778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.497375Z","time spent":"309.355561ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:57.806934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.471411ms","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-11T01:52:57.806998Z","caller":"traceutil/trace.go:171","msg":"trace[1571352127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:929; }","duration":"148.564694ms","start":"2026-02-11T01:52:57.658416Z","end":"2026-02-11T01:52:57.806981Z","steps":["trace[1571352127] 'agreement among raft nodes before linearized reading' (duration: 148.40309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.807089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.142039ms","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-11T01:52:57.80711Z","caller":"traceutil/trace.go:171","msg":"trace[1624757057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"199.18441ms","start":"2026-02-11T01:52:57.60792Z","end":"2026-02-11T01:52:57.807104Z","steps":["trace[1624757057] 'agreement among raft nodes before linearized reading' (duration: 199.141769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:57.807173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.110684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:52:57.807184Z","caller":"traceutil/trace.go:171","msg":"trace[1898801630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"102.145934ms","start":"2026-02-11T01:52:57.705035Z","end":"2026-02-11T01:52:57.807181Z","steps":["trace[1898801630] 'agreement among raft nodes before linearized reading' (duration: 102.113063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:58.691227Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014488,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:52:59.052265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.209474ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:59.052378Z","caller":"traceutil/trace.go:171","msg":"trace[1711957211] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:929; }","duration":"533.329237ms","start":"2026-02-11T01:52:58.519033Z","end":"2026-02-11T01:52:59.052362Z","steps":["trace[1711957211] 'range keys from in-memory index tree' (duration: 533.194684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:59.052746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.135296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:52:59.053048Z","caller":"traceutil/trace.go:171","msg":"trace[1119996246] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:968; }","duration":"862.030848ms","start":"2026-02-11T01:52:58.191001Z","end":"2026-02-11T01:52:59.053032Z","steps":["trace[1119996246] 'read index received' (duration: 609.927438ms)","trace[1119996246] 'applied index is now lower than readState.Index' (duration: 252.10248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:59.053212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.204922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:52:59.053239Z","caller":"traceutil/trace.go:171","msg":"trace[341914099] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:934; }","duration":"862.256182ms","start":"2026-02-11T01:52:58.190974Z","end":"2026-02-11T01:52:59.05323Z","steps":["trace[341914099] 'agreement among raft nodes before linearized reading' (duration: 862.127659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:59.05327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:58.190961Z","time spent":"862.302584ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"info","ts":"2026-02-11T01:52:59.053534Z","caller":"traceutil/trace.go:171","msg":"trace[1136258601] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.240254729s","start":"2026-02-11T01:52:57.813265Z","end":"2026-02-11T01:52:59.05352Z","steps":["trace[1136258601] 'process raft request' (duration: 249.247613ms)","trace[1136258601] 'compare' (duration: 990.043204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:59.053593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.813251Z","time spent":"1.240310281s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:59.053711Z","caller":"traceutil/trace.go:171","msg":"trace[2061887819] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.2402876s","start":"2026-02-11T01:52:57.813414Z","end":"2026-02-11T01:52:59.053702Z","steps":["trace[2061887819] 'process raft request' (duration: 1.23942079s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:59.053765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.813407Z","time spent":"1.240329491s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:59.053877Z","caller":"traceutil/trace.go:171","msg":"trace[1881312567] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"1.235836507s","start":"2026-02-11T01:52:57.818032Z","end":"2026-02-11T01:52:59.053868Z","steps":["trace[1881312567] 'process raft request' (duration: 1.234853024s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:59.053923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.818022Z","time spent":"1.235876178s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:59.054031Z","caller":"traceutil/trace.go:171","msg":"trace[967581092] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.234744382s","start":"2026-02-11T01:52:57.819277Z","end":"2026-02-11T01:52:59.054021Z","steps":["trace[967581092] 'process raft request' (duration: 1.233641536s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:59.054075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.819264Z","time spent":"1.234787472s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:59.054178Z","caller":"traceutil/trace.go:171","msg":"trace[1788047546] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.233664656s","start":"2026-02-11T01:52:57.820506Z","end":"2026-02-11T01:52:59.05417Z","steps":["trace[1788047546] 'process raft request' (duration: 1.232445758s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:59.054614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:57.820499Z","time spent":"1.234056515s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:04.201982Z","caller":"traceutil/trace.go:171","msg":"trace[911057710] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"349.79041ms","start":"2026-02-11T01:53:03.852174Z","end":"2026-02-11T01:53:04.201964Z","steps":["trace[911057710] 'process raft request' (duration: 349.682948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:04.202084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:03.852164Z","time spent":"349.867513ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:04.564886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.941498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:04.565015Z","caller":"traceutil/trace.go:171","msg":"trace[907294065] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:947; }","duration":"302.129783ms","start":"2026-02-11T01:53:04.262866Z","end":"2026-02-11T01:53:04.564996Z","steps":["trace[907294065] 'count revisions from in-memory index tree' (duration: 301.869867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:04.565083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:04.262853Z","time spent":"302.197885ms","remote":"127.0.0.1:50230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":95,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:53:05.57653Z","caller":"traceutil/trace.go:171","msg":"trace[10314760] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"476.26344ms","start":"2026-02-11T01:53:05.100253Z","end":"2026-02-11T01:53:05.576516Z","steps":["trace[10314760] 'read index received' (duration: 476.144237ms)","trace[10314760] 'applied index is now lower than readState.Index' (duration: 118.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:05.576706Z","caller":"traceutil/trace.go:171","msg":"trace[1993509319] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"479.947915ms","start":"2026-02-11T01:53:05.09675Z","end":"2026-02-11T01:53:05.576698Z","steps":["trace[1993509319] 'process raft request' (duration: 479.683309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:05.576765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:05.096741Z","time spent":"479.979997ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:05.576913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.659709ms","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-11T01:53:05.57693Z","caller":"traceutil/trace.go:171","msg":"trace[796027697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"476.70101ms","start":"2026-02-11T01:53:05.100223Z","end":"2026-02-11T01:53:05.576924Z","steps":["trace[796027697] 'agreement among raft nodes before linearized reading' (duration: 476.637469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:05.576944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:05.10021Z","time spent":"476.731101ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:05.577039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.124507ms","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-11T01:53:05.577052Z","caller":"traceutil/trace.go:171","msg":"trace[523282736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"476.147987ms","start":"2026-02-11T01:53:05.1009Z","end":"2026-02-11T01:53:05.577048Z","steps":["trace[523282736] 'agreement among raft nodes before linearized reading' (duration: 476.112386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:05.577064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:05.100894Z","time spent":"476.166527ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:53:05.577161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.671148ms","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-11T01:53:05.577175Z","caller":"traceutil/trace.go:171","msg":"trace[828398171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"457.696788ms","start":"2026-02-11T01:53:05.119475Z","end":"2026-02-11T01:53:05.577171Z","steps":["trace[828398171] 'agreement among raft nodes before linearized reading' (duration: 457.659157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:05.577186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:05.119468Z","time spent":"457.714738ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:05.965353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.09023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:05.965468Z","caller":"traceutil/trace.go:171","msg":"trace[1770635927] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"382.517732ms","start":"2026-02-11T01:53:05.582938Z","end":"2026-02-11T01:53:05.965455Z","steps":["trace[1770635927] 'process raft request' (duration: 382.479011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:05.965509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:05.582929Z","time spent":"382.560263ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:05.965734Z","caller":"traceutil/trace.go:171","msg":"trace[1789171765] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"383.752591ms","start":"2026-02-11T01:53:05.581972Z","end":"2026-02-11T01:53:05.965724Z","steps":["trace[1789171765] 'process raft request' (duration: 251.23671ms)","trace[1789171765] 'compare' (duration: 131.970178ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:05.96577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:05.581947Z","time spent":"383.803452ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:06.916604Z","caller":"traceutil/trace.go:171","msg":"trace[69831103] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"261.715253ms","start":"2026-02-11T01:53:06.654864Z","end":"2026-02-11T01:53:06.916579Z","steps":["trace[69831103] 'read index received' (duration: 261.55466ms)","trace[69831103] 'applied index is now lower than readState.Index' (duration: 159.963µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:06.916707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.825876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:06.916741Z","caller":"traceutil/trace.go:171","msg":"trace[903623029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"261.903128ms","start":"2026-02-11T01:53:06.65483Z","end":"2026-02-11T01:53:06.916733Z","steps":["trace[903623029] 'agreement among raft nodes before linearized reading' (duration: 261.819286ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:06.916955Z","caller":"traceutil/trace.go:171","msg":"trace[1922938505] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"704.23278ms","start":"2026-02-11T01:53:06.212714Z","end":"2026-02-11T01:53:06.916947Z","steps":["trace[1922938505] 'process raft request' (duration: 703.752899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:06.917055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:06.212705Z","time spent":"704.309952ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:08.158983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:08.659747Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:08.839726Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.254206005s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:53:08.840006Z","caller":"traceutil/trace.go:171","msg":"trace[364887018] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"1.181836262s","start":"2026-02-11T01:53:07.658148Z","end":"2026-02-11T01:53:08.839984Z","steps":["trace[364887018] 'read index received' (duration: 1.181687589s)","trace[364887018] 'applied index is now lower than readState.Index' (duration: 147.753µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:08.840118Z","caller":"traceutil/trace.go:171","msg":"trace[590489843] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"1.254675325s","start":"2026-02-11T01:53:07.585433Z","end":"2026-02-11T01:53:08.840109Z","steps":["trace[590489843] 'process raft request' (duration: 1.254418669s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:08.840339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:07.585417Z","time spent":"1.25485033s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:08.840824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182666172s","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-11T01:53:08.840868Z","caller":"traceutil/trace.go:171","msg":"trace[1758479226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"1.182737494s","start":"2026-02-11T01:53:07.658118Z","end":"2026-02-11T01:53:08.840855Z","steps":["trace[1758479226] 'agreement among raft nodes before linearized reading' (duration: 1.18259257s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:08.840902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:07.658105Z","time spent":"1.182788725s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:53:08.84146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.105032ms","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-11T01:53:08.841505Z","caller":"traceutil/trace.go:171","msg":"trace[2044959750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"872.173424ms","start":"2026-02-11T01:53:07.969318Z","end":"2026-02-11T01:53:08.841492Z","steps":["trace[2044959750] 'agreement among raft nodes before linearized reading' (duration: 872.057912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:08.84154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:07.969304Z","time spent":"872.225785ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:08.842159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.007783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T01:53:08.842202Z","caller":"traceutil/trace.go:171","msg":"trace[618561114] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:953; }","duration":"651.086735ms","start":"2026-02-11T01:53:08.191104Z","end":"2026-02-11T01:53:08.842191Z","steps":["trace[618561114] 'agreement among raft nodes before linearized reading' (duration: 650.960462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:08.842234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.191088Z","time spent":"651.138256ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-02-11T01:53:08.842554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.719606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:08.842596Z","caller":"traceutil/trace.go:171","msg":"trace[1522306133] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:953; }","duration":"727.770397ms","start":"2026-02-11T01:53:08.114813Z","end":"2026-02-11T01:53:08.842584Z","steps":["trace[1522306133] 'agreement among raft nodes before linearized reading' (duration: 727.692685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:08.842632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.114746Z","time spent":"727.87598ms","remote":"127.0.0.1:38502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:08.842923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.688247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:08.84296Z","caller":"traceutil/trace.go:171","msg":"trace[2034373383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"783.753959ms","start":"2026-02-11T01:53:08.059195Z","end":"2026-02-11T01:53:08.842949Z","steps":["trace[2034373383] 'agreement among raft nodes before linearized reading' (duration: 783.693028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:08.84299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.059179Z","time spent":"783.80426ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:08.843311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.654438ms","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-11T01:53:08.843352Z","caller":"traceutil/trace.go:171","msg":"trace[1013504791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"873.70024ms","start":"2026-02-11T01:53:07.969636Z","end":"2026-02-11T01:53:08.843337Z","steps":["trace[1013504791] 'agreement among raft nodes before linearized reading' (duration: 873.592118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:08.843386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:07.969633Z","time spent":"873.745711ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:09.424335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014549,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:09.924831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014549,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:09.935265Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083041585s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T01:53:09.935482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.852134Z","time spent":"1.083345752s","remote":"127.0.0.1:49772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T01:53:10.011103Z","caller":"traceutil/trace.go:171","msg":"trace[1562341369] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.15685015s","start":"2026-02-11T01:53:08.854231Z","end":"2026-02-11T01:53:10.011082Z","steps":["trace[1562341369] 'process raft request' (duration: 1.156558454s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.011243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.854223Z","time spent":"1.156946923s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:10.011373Z","caller":"traceutil/trace.go:171","msg":"trace[518339322] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.152900619s","start":"2026-02-11T01:53:08.858431Z","end":"2026-02-11T01:53:10.011332Z","steps":["trace[518339322] 'process raft request' (duration: 1.152583802s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.011466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.858425Z","time spent":"1.152988511s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:10.011647Z","caller":"traceutil/trace.go:171","msg":"trace[281407490] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.152053069s","start":"2026-02-11T01:53:08.859582Z","end":"2026-02-11T01:53:10.011636Z","steps":["trace[281407490] 'process raft request' (duration: 1.151699141s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.011715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.859577Z","time spent":"1.15210326s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:10.011724Z","caller":"traceutil/trace.go:171","msg":"trace[1068568769] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:995; }","duration":"1.090837806s","start":"2026-02-11T01:53:08.92088Z","end":"2026-02-11T01:53:10.011718Z","steps":["trace[1068568769] 'read index received' (duration: 1.014827009s)","trace[1068568769] 'applied index is now lower than readState.Index' (duration: 76.010117ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:10.011803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090921698s","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-11T01:53:10.011818Z","caller":"traceutil/trace.go:171","msg":"trace[325568705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"1.090960309s","start":"2026-02-11T01:53:08.920853Z","end":"2026-02-11T01:53:10.011813Z","steps":["trace[325568705] 'agreement among raft nodes before linearized reading' (duration: 1.090888478s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.011833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:08.92084Z","time spent":"1.09099019s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:53:10.011918Z","caller":"traceutil/trace.go:171","msg":"trace[621592194] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"852.19036ms","start":"2026-02-11T01:53:09.159715Z","end":"2026-02-11T01:53:10.011905Z","steps":["trace[621592194] 'process raft request' (duration: 851.869132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.011993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:09.159684Z","time spent":"852.268182ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:10.498942Z","caller":"traceutil/trace.go:171","msg":"trace[2131834860] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"561.777569ms","start":"2026-02-11T01:53:09.937149Z","end":"2026-02-11T01:53:10.498926Z","steps":["trace[2131834860] 'process raft request' (duration: 491.458614ms)","trace[2131834860] 'compare' (duration: 70.11116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:10.49903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:09.937135Z","time spent":"561.852761ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:10.509802Z","caller":"traceutil/trace.go:171","msg":"trace[948494489] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"454.280131ms","start":"2026-02-11T01:53:10.055512Z","end":"2026-02-11T01:53:10.509792Z","steps":["trace[948494489] 'read index received' (duration: 373.104384ms)","trace[948494489] 'applied index is now lower than readState.Index' (duration: 81.175257ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:10.509958Z","caller":"traceutil/trace.go:171","msg":"trace[291741148] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"493.771268ms","start":"2026-02-11T01:53:10.01618Z","end":"2026-02-11T01:53:10.509951Z","steps":["trace[291741148] 'process raft request' (duration: 493.534343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.51001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:10.01616Z","time spent":"493.8133ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:10.510088Z","caller":"traceutil/trace.go:171","msg":"trace[1457821692] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"361.209677ms","start":"2026-02-11T01:53:10.148874Z","end":"2026-02-11T01:53:10.510083Z","steps":["trace[1457821692] 'process raft request' (duration: 360.90035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.51011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:10.148853Z","time spent":"361.243968ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:10.510177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.671029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:10.510195Z","caller":"traceutil/trace.go:171","msg":"trace[2081316740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"454.716811ms","start":"2026-02-11T01:53:10.055473Z","end":"2026-02-11T01:53:10.51019Z","steps":["trace[2081316740] 'agreement among raft nodes before linearized reading' (duration: 454.6846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:10.51021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:10.055459Z","time spent":"454.748451ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:12.559917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:13.060171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:13.561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:13.884223Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.863975338s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:53:13.884893Z","caller":"traceutil/trace.go:171","msg":"trace[1834858394] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.864692576s","start":"2026-02-11T01:53:12.020178Z","end":"2026-02-11T01:53:13.884871Z","steps":["trace[1834858394] 'process raft request' (duration: 1.864548222s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:13.88517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.020158Z","time spent":"1.86491201s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:14.059338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000333998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:53:14.059417Z","caller":"traceutil/trace.go:171","msg":"trace[1352789018] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000457782s","start":"2026-02-11T01:53:12.058941Z","end":"2026-02-11T01:53:14.059399Z","steps":["trace[1352789018] 'agreement among raft nodes before linearized reading' (duration: 2.000351869s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:14.059468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.058931Z","time spent":"2.000524783s","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:15.968456Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.750620615s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T01:53:16.060294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000179036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:53:16.060367Z","caller":"traceutil/trace.go:171","msg":"trace[904398969] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000305898s","start":"2026-02-11T01:53:14.060044Z","end":"2026-02-11T01:53:16.06035Z","steps":["trace[904398969] 'agreement among raft nodes before linearized reading' (duration: 2.000214296s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:16.060425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:14.060028Z","time spent":"2.000387161s","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:53:16 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:53:17.023161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.024196Z","time spent":"4.998958772s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-11T01:53:17.53389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565193692s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:17.534618Z","caller":"traceutil/trace.go:171","msg":"trace[307644618] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1003; }","duration":"5.475633353s","start":"2026-02-11T01:53:12.058967Z","end":"2026-02-11T01:53:17.534601Z","steps":["trace[307644618] 'read index received' (duration: 1.825667748s)","trace[307644618] 'applied index is now lower than readState.Index' (duration: 3.649964575s)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:17.535205Z","caller":"traceutil/trace.go:171","msg":"trace[1313806259] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"5.511950048s","start":"2026-02-11T01:53:12.023239Z","end":"2026-02-11T01:53:17.535189Z","steps":["trace[1313806259] 'process raft request' (duration: 3.945372563s)","trace[1313806259] 'compare' (duration: 1.564042976s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:17.535668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.023226Z","time spent":"5.512400138s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:17.880805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.995554024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:53:17.880892Z","caller":"traceutil/trace.go:171","msg":"trace[915296048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.995685627s","start":"2026-02-11T01:53:12.885189Z","end":"2026-02-11T01:53:17.880874Z","steps":["trace[915296048] 'agreement among raft nodes before linearized reading' (duration: 4.995577855s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:17.881036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.885175Z","time spent":"4.995848781s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/11 01:53:17 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:53:18.027221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.001354ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T01:53:18.027333Z","caller":"traceutil/trace.go:171","msg":"trace[757000833] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"492.312495ms","start":"2026-02-11T01:53:17.535004Z","end":"2026-02-11T01:53:18.027317Z","steps":["trace[757000833] 'read index received' (duration: 38.111266ms)","trace[757000833] 'applied index is now lower than readState.Index' (duration: 454.199799ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:18.027483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.513260047s","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-11T01:53:18.027509Z","caller":"traceutil/trace.go:171","msg":"trace[1269393669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"5.513316899s","start":"2026-02-11T01:53:12.514184Z","end":"2026-02-11T01:53:18.0275Z","steps":["trace[1269393669] 'agreement among raft nodes before linearized reading' (duration: 5.513175996s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:18.027541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.51417Z","time spent":"5.51336383s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:18.027524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.980168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:18.027626Z","caller":"traceutil/trace.go:171","msg":"trace[869397891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"853.121562ms","start":"2026-02-11T01:53:17.174488Z","end":"2026-02-11T01:53:18.027609Z","steps":["trace[869397891] 'agreement among raft nodes before linearized reading' (duration: 852.976318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:18.027665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:17.174467Z","time spent":"853.191043ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:18.027833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89412936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:18.027863Z","caller":"traceutil/trace.go:171","msg":"trace[955244578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"1.894195851s","start":"2026-02-11T01:53:16.133659Z","end":"2026-02-11T01:53:18.027854Z","steps":["trace[955244578] 'agreement among raft nodes before linearized reading' (duration: 1.89414361s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:18.028124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.138290165s","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-11T01:53:18.028189Z","caller":"traceutil/trace.go:171","msg":"trace[1975310124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"2.138391388s","start":"2026-02-11T01:53:15.889788Z","end":"2026-02-11T01:53:18.028179Z","steps":["trace[1975310124] 'agreement among raft nodes before linearized reading' (duration: 2.138236684s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:18.028216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:15.889769Z","time spent":"2.138440668s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:53:18.028404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.947315346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18930e38c1025803\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-11T01:53:18.028438Z","caller":"traceutil/trace.go:171","msg":"trace[1916480550] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18930e38c1025803; range_end:; response_count:1; response_revision:964; }","duration":"1.947387698s","start":"2026-02-11T01:53:16.081041Z","end":"2026-02-11T01:53:18.028429Z","steps":["trace[1916480550] 'agreement among raft nodes before linearized reading' (duration: 1.947297995s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:18.028463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:16.08102Z","time spent":"1.947437028s","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18930e38c1025803\" "} {"level":"warn","ts":"2026-02-11T01:53:18.028791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.508157129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:18.028824Z","caller":"traceutil/trace.go:171","msg":"trace[781609201] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:964; }","duration":"5.508200209s","start":"2026-02-11T01:53:12.520613Z","end":"2026-02-11T01:53:18.028813Z","steps":["trace[781609201] 'agreement among raft nodes before linearized reading' (duration: 5.508149198s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:18.028851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:12.520607Z","time spent":"5.508236271s","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:18.027888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:16.133642Z","time spent":"1.894240612s","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:53:18.138385Z","caller":"traceutil/trace.go:171","msg":"trace[1266064979] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"104.338435ms","start":"2026-02-11T01:53:18.03402Z","end":"2026-02-11T01:53:18.138359Z","steps":["trace[1266064979] 'process raft request' (duration: 104.165421ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:18.219421Z","caller":"traceutil/trace.go:171","msg":"trace[754357369] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"183.578088ms","start":"2026-02-11T01:53:18.035822Z","end":"2026-02-11T01:53:18.2194Z","steps":["trace[754357369] 'read index received' (duration: 102.526954ms)","trace[754357369] 'applied index is now lower than readState.Index' (duration: 81.050344ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:18.219626Z","caller":"traceutil/trace.go:171","msg":"trace[636363970] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"183.884644ms","start":"2026-02-11T01:53:18.035714Z","end":"2026-02-11T01:53:18.219599Z","steps":["trace[636363970] 'process raft request' (duration: 183.508446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:18.220106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.269414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:18.220147Z","caller":"traceutil/trace.go:171","msg":"trace[1148369939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"184.333135ms","start":"2026-02-11T01:53:18.035803Z","end":"2026-02-11T01:53:18.220136Z","steps":["trace[1148369939] 'agreement among raft nodes before linearized reading' (duration: 184.265383ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:18.219873Z","caller":"traceutil/trace.go:171","msg":"trace[363584067] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"182.494072ms","start":"2026-02-11T01:53:18.037357Z","end":"2026-02-11T01:53:18.219851Z","steps":["trace[363584067] 'process raft request' (duration: 182.006991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:19.044618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.443134ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:19.046671Z","caller":"traceutil/trace.go:171","msg":"trace[1386705649] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:967; }","duration":"527.514873ms","start":"2026-02-11T01:53:18.519142Z","end":"2026-02-11T01:53:19.046656Z","steps":["trace[1386705649] 'range keys from in-memory index tree' (duration: 525.431834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:19.046613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.183871ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-11T01:53:19.046845Z","caller":"traceutil/trace.go:171","msg":"trace[299919660] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"817.116564ms","start":"2026-02-11T01:53:18.229719Z","end":"2026-02-11T01:53:19.046836Z","steps":["trace[299919660] 'read index received' (duration: 176.646556ms)","trace[299919660] 'applied index is now lower than readState.Index' (duration: 640.469118ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:19.04691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:18.222729Z","time spent":"824.178368ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T01:53:19.047165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.437411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:19.047196Z","caller":"traceutil/trace.go:171","msg":"trace[1745252081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"817.485383ms","start":"2026-02-11T01:53:18.229702Z","end":"2026-02-11T01:53:19.047187Z","steps":["trace[1745252081] 'agreement among raft nodes before linearized reading' (duration: 817.429392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:19.047223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:18.229692Z","time spent":"817.525764ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:19.047755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.82755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:19.047801Z","caller":"traceutil/trace.go:171","msg":"trace[966841675] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:967; }","duration":"216.908852ms","start":"2026-02-11T01:53:18.830876Z","end":"2026-02-11T01:53:19.047785Z","steps":["trace[966841675] 'agreement among raft nodes before linearized reading' (duration: 216.8371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:19.81729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.75865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:19.817367Z","caller":"traceutil/trace.go:171","msg":"trace[1224235884] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"766.396075ms","start":"2026-02-11T01:53:19.050957Z","end":"2026-02-11T01:53:19.817353Z","steps":["trace[1224235884] 'read index received' (duration: 219.516263ms)","trace[1224235884] 'applied index is now lower than readState.Index' (duration: 546.878682ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:19.817428Z","caller":"traceutil/trace.go:171","msg":"trace[1516532354] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"767.379369ms","start":"2026-02-11T01:53:19.050037Z","end":"2026-02-11T01:53:19.817417Z","steps":["trace[1516532354] 'process raft request' (duration: 220.425404ms)","trace[1516532354] 'compare' (duration: 546.428222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:19.817477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:19.050019Z","time spent":"767.431949ms","remote":"127.0.0.1:49772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:19.817686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.724024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:19.817715Z","caller":"traceutil/trace.go:171","msg":"trace[1303168420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"766.752994ms","start":"2026-02-11T01:53:19.050953Z","end":"2026-02-11T01:53:19.817706Z","steps":["trace[1303168420] 'agreement among raft nodes before linearized reading' (duration: 766.682053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:19.817741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:19.050918Z","time spent":"766.818145ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:53:19.820203Z","caller":"traceutil/trace.go:171","msg":"trace[2092532868] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"766.509798ms","start":"2026-02-11T01:53:19.053336Z","end":"2026-02-11T01:53:19.819846Z","steps":["trace[2092532868] 'process raft request' (duration: 766.367385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:19.820261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:19.053271Z","time spent":"766.955129ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:19.820413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.453569ms","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-11T01:53:19.820432Z","caller":"traceutil/trace.go:171","msg":"trace[1920448081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"280.506521ms","start":"2026-02-11T01:53:19.53992Z","end":"2026-02-11T01:53:19.820426Z","steps":["trace[1920448081] 'agreement among raft nodes before linearized reading' (duration: 280.437699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:20.448576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.998642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:20.448726Z","caller":"traceutil/trace.go:171","msg":"trace[80087193] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"399.415195ms","start":"2026-02-11T01:53:20.049296Z","end":"2026-02-11T01:53:20.448711Z","steps":["trace[80087193] 'process raft request' (duration: 399.364333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:20.448784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.04928Z","time spent":"399.475537ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:20.449088Z","caller":"traceutil/trace.go:171","msg":"trace[1924296502] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"622.880328ms","start":"2026-02-11T01:53:19.826193Z","end":"2026-02-11T01:53:20.449073Z","steps":["trace[1924296502] 'process raft request' (duration: 338.314643ms)","trace[1924296502] 'compare' (duration: 283.785807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:20.449145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:19.826185Z","time spent":"622.92984ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:20.449222Z","caller":"traceutil/trace.go:171","msg":"trace[1703119972] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"568.71744ms","start":"2026-02-11T01:53:19.880496Z","end":"2026-02-11T01:53:20.449214Z","steps":["trace[1703119972] 'read index received' (duration: 284.025152ms)","trace[1703119972] 'applied index is now lower than readState.Index' (duration: 284.691568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:20.449344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.842143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:53:20.449369Z","caller":"traceutil/trace.go:171","msg":"trace[750166938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"568.879655ms","start":"2026-02-11T01:53:19.880481Z","end":"2026-02-11T01:53:20.449361Z","steps":["trace[750166938] 'agreement among raft nodes before linearized reading' (duration: 568.789653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:20.449394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:19.880474Z","time spent":"568.914945ms","remote":"127.0.0.1:49992","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-11T01:53:20.449526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.423135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:20.449552Z","caller":"traceutil/trace.go:171","msg":"trace[1519144836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"393.475937ms","start":"2026-02-11T01:53:20.056068Z","end":"2026-02-11T01:53:20.449544Z","steps":["trace[1519144836] 'agreement among raft nodes before linearized reading' (duration: 393.440786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:20.449575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.056049Z","time spent":"393.520738ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:20.449805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.922135ms","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-11T01:53:20.44987Z","caller":"traceutil/trace.go:171","msg":"trace[1241063850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:971; }","duration":"224.032828ms","start":"2026-02-11T01:53:20.22582Z","end":"2026-02-11T01:53:20.449853Z","steps":["trace[1241063850] 'agreement among raft nodes before linearized reading' (duration: 223.835034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:21.395724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014597,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:21.540594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.703709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:21.540679Z","caller":"traceutil/trace.go:171","msg":"trace[1641993812] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"1.085503684s","start":"2026-02-11T01:53:20.455158Z","end":"2026-02-11T01:53:21.540661Z","steps":["trace[1641993812] 'process raft request' (duration: 384.666112ms)","trace[1641993812] 'compare' (duration: 700.412062ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:21.540736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.455141Z","time spent":"1.085569155s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:21.895889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014597,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:22.396349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014597,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:53:22.500234Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.65188014s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:53:22.500599Z","caller":"traceutil/trace.go:171","msg":"trace[1422255053] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1016; }","duration":"1.604973149s","start":"2026-02-11T01:53:20.895609Z","end":"2026-02-11T01:53:22.500582Z","steps":["trace[1422255053] 'read index received' (duration: 1.604715893s)","trace[1422255053] 'applied index is now lower than readState.Index' (duration: 256.596µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:22.500664Z","caller":"traceutil/trace.go:171","msg":"trace[144733454] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"2.043874382s","start":"2026-02-11T01:53:20.456783Z","end":"2026-02-11T01:53:22.500657Z","steps":["trace[144733454] 'process raft request' (duration: 2.043599705s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.500744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.456774Z","time spent":"2.043908842s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:22.500836Z","caller":"traceutil/trace.go:171","msg":"trace[1198470685] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"2.04123638s","start":"2026-02-11T01:53:20.459594Z","end":"2026-02-11T01:53:22.50083Z","steps":["trace[1198470685] 'process raft request' (duration: 2.040930243s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.500867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.459584Z","time spent":"2.041264461s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:22.500924Z","caller":"traceutil/trace.go:171","msg":"trace[1253426020] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.932266457s","start":"2026-02-11T01:53:20.568649Z","end":"2026-02-11T01:53:22.500915Z","steps":["trace[1253426020] 'process raft request' (duration: 1.931908968s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.501007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.568613Z","time spent":"1.932333348s","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:22.501093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605482481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:22.501115Z","caller":"traceutil/trace.go:171","msg":"trace[287578374] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:975; }","duration":"1.605545462s","start":"2026-02-11T01:53:20.895563Z","end":"2026-02-11T01:53:22.501108Z","steps":["trace[287578374] 'agreement among raft nodes before linearized reading' (duration: 1.605502031s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.50114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:20.895525Z","time spent":"1.605609904s","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:22.501308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443041745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:22.501332Z","caller":"traceutil/trace.go:171","msg":"trace[1795552946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"1.443095226s","start":"2026-02-11T01:53:21.058226Z","end":"2026-02-11T01:53:22.501321Z","steps":["trace[1795552946] 'agreement among raft nodes before linearized reading' (duration: 1.443065995s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.501365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:21.058208Z","time spent":"1.443148217s","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T01:53:22.501697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.209569ms","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-11T01:53:22.501762Z","caller":"traceutil/trace.go:171","msg":"trace[1756006452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"676.314442ms","start":"2026-02-11T01:53:21.825428Z","end":"2026-02-11T01:53:22.501743Z","steps":["trace[1756006452] 'agreement among raft nodes before linearized reading' (duration: 676.139108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.501801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:21.82541Z","time spent":"676.383123ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:22.829615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.811071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:22.82973Z","caller":"traceutil/trace.go:171","msg":"trace[1528146281] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"317.415649ms","start":"2026-02-11T01:53:22.512295Z","end":"2026-02-11T01:53:22.829711Z","steps":["trace[1528146281] 'process raft request' (duration: 180.436265ms)","trace[1528146281] 'compare' (duration: 136.681007ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:22.829786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:22.512263Z","time spent":"317.496761ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:22.863173Z","caller":"traceutil/trace.go:171","msg":"trace[491807587] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"350.095528ms","start":"2026-02-11T01:53:22.513063Z","end":"2026-02-11T01:53:22.863158Z","steps":["trace[491807587] 'read index received' (duration: 179.796049ms)","trace[491807587] 'applied index is now lower than readState.Index' (duration: 170.298769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:22.863246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.16799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:22.863263Z","caller":"traceutil/trace.go:171","msg":"trace[1221252082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"350.21363ms","start":"2026-02-11T01:53:22.513045Z","end":"2026-02-11T01:53:22.863258Z","steps":["trace[1221252082] 'agreement among raft nodes before linearized reading' (duration: 350.170279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.86328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:22.513038Z","time spent":"350.238241ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T01:53:22.863353Z","caller":"traceutil/trace.go:171","msg":"trace[1574812578] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"351.024949ms","start":"2026-02-11T01:53:22.512313Z","end":"2026-02-11T01:53:22.863338Z","steps":["trace[1574812578] 'process raft request' (duration: 350.778014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:22.863438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:22.512293Z","time spent":"351.105132ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:23.837036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.557917ms","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-11T01:53:23.83712Z","caller":"traceutil/trace.go:171","msg":"trace[2138373716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:977; }","duration":"291.69815ms","start":"2026-02-11T01:53:23.5454Z","end":"2026-02-11T01:53:23.837098Z","steps":["trace[2138373716] 'range keys from in-memory index tree' (duration: 291.336982ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:23.988098Z","caller":"traceutil/trace.go:171","msg":"trace[654172694] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"144.650243ms","start":"2026-02-11T01:53:23.84343Z","end":"2026-02-11T01:53:23.98808Z","steps":["trace[654172694] 'process raft request' (duration: 144.555361ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:24.672522Z","caller":"traceutil/trace.go:171","msg":"trace[2054169399] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"162.627871ms","start":"2026-02-11T01:53:24.509882Z","end":"2026-02-11T01:53:24.67251Z","steps":["trace[2054169399] 'process raft request' (duration: 162.528409ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:24.67282Z","caller":"traceutil/trace.go:171","msg":"trace[701538151] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1023; }","duration":"161.884184ms","start":"2026-02-11T01:53:24.510929Z","end":"2026-02-11T01:53:24.672813Z","steps":["trace[701538151] 'read index received' (duration: 161.881954ms)","trace[701538151] 'applied index is now lower than readState.Index' (duration: 1.86µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:24.672897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.955286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:53:24.672911Z","caller":"traceutil/trace.go:171","msg":"trace[1229189274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"161.999716ms","start":"2026-02-11T01:53:24.510907Z","end":"2026-02-11T01:53:24.672906Z","steps":["trace[1229189274] 'agreement among raft nodes before linearized reading' (duration: 161.928325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:25.051898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.244374ms","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-11T01:53:25.051953Z","caller":"traceutil/trace.go:171","msg":"trace[1041592505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"218.329475ms","start":"2026-02-11T01:53:24.833612Z","end":"2026-02-11T01:53:25.051941Z","steps":["trace[1041592505] 'range keys from in-memory index tree' (duration: 218.127451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:25.052131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.574363ms","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-11T01:53:25.052154Z","caller":"traceutil/trace.go:171","msg":"trace[422959148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"185.621395ms","start":"2026-02-11T01:53:24.866527Z","end":"2026-02-11T01:53:25.052149Z","steps":["trace[422959148] 'range keys from in-memory index tree' (duration: 185.505073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:25.207009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.743808ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T01:53:25.207259Z","caller":"traceutil/trace.go:171","msg":"trace[1041285314] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"128.649081ms","start":"2026-02-11T01:53:25.078584Z","end":"2026-02-11T01:53:25.207233Z","steps":["trace[1041285314] 'process raft request' (duration: 128.60219ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:25.2076Z","caller":"traceutil/trace.go:171","msg":"trace[1792098224] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"145.957113ms","start":"2026-02-11T01:53:25.061628Z","end":"2026-02-11T01:53:25.207585Z","steps":["trace[1792098224] 'read index received' (duration: 39.743194ms)","trace[1792098224] 'applied index is now lower than readState.Index' (duration: 106.212859ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:25.207655Z","caller":"traceutil/trace.go:171","msg":"trace[1365462675] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"144.796485ms","start":"2026-02-11T01:53:25.06285Z","end":"2026-02-11T01:53:25.207647Z","steps":["trace[1365462675] 'process raft request' (duration: 144.236772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:25.217018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.379372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:53:25.217047Z","caller":"traceutil/trace.go:171","msg":"trace[533050938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"155.434453ms","start":"2026-02-11T01:53:25.061605Z","end":"2026-02-11T01:53:25.21704Z","steps":["trace[533050938] 'agreement among raft nodes before linearized reading' (duration: 146.023984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.041718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.636092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:53:27.041858Z","caller":"traceutil/trace.go:171","msg":"trace[552302032] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"431.338946ms","start":"2026-02-11T01:53:26.61051Z","end":"2026-02-11T01:53:27.041849Z","steps":["trace[552302032] 'process raft request' (duration: 431.273044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.041905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.6105Z","time spent":"431.388227ms","remote":"127.0.0.1:50194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8757,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:27.042075Z","caller":"traceutil/trace.go:171","msg":"trace[132627612] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"437.345586ms","start":"2026-02-11T01:53:26.604722Z","end":"2026-02-11T01:53:27.042067Z","steps":["trace[132627612] 'process raft request' (duration: 33.307704ms)","trace[132627612] 'compare' (duration: 403.325595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:27.042099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.604707Z","time spent":"437.378866ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:27.084806Z","caller":"traceutil/trace.go:171","msg":"trace[1981917092] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1035; }","duration":"406.624722ms","start":"2026-02-11T01:53:26.678165Z","end":"2026-02-11T01:53:27.08479Z","steps":["trace[1981917092] 'read index received' (duration: 362.747912ms)","trace[1981917092] 'applied index is now lower than readState.Index' (duration: 43.87627ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:27.085038Z","caller":"traceutil/trace.go:171","msg":"trace[1771720205] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"416.998643ms","start":"2026-02-11T01:53:26.668033Z","end":"2026-02-11T01:53:27.085031Z","steps":["trace[1771720205] 'process raft request' (duration: 416.651775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.085104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.668009Z","time spent":"417.049594ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:53:27.085235Z","caller":"traceutil/trace.go:171","msg":"trace[379734424] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"332.084398ms","start":"2026-02-11T01:53:26.753145Z","end":"2026-02-11T01:53:27.085229Z","steps":["trace[379734424] 'process raft request' (duration: 331.614457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.08526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.75313Z","time spent":"332.115069ms","remote":"127.0.0.1:49924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:27.085357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.191535ms","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-11T01:53:27.085377Z","caller":"traceutil/trace.go:171","msg":"trace[280367547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"407.228426ms","start":"2026-02-11T01:53:26.678144Z","end":"2026-02-11T01:53:27.085373Z","steps":["trace[280367547] 'agreement among raft nodes before linearized reading' (duration: 407.162114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.085392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.678136Z","time spent":"407.251326ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:27.085493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.468613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T01:53:27.08551Z","caller":"traceutil/trace.go:171","msg":"trace[1693931825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"346.513845ms","start":"2026-02-11T01:53:26.738992Z","end":"2026-02-11T01:53:27.085506Z","steps":["trace[1693931825] 'agreement among raft nodes before linearized reading' (duration: 346.453373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:27.085522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:26.738976Z","time spent":"346.543445ms","remote":"127.0.0.1:49992","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":"info","ts":"2026-02-11T01:53:27.215199Z","caller":"traceutil/trace.go:171","msg":"trace[833665300] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"120.043451ms","start":"2026-02-11T01:53:27.095134Z","end":"2026-02-11T01:53:27.215177Z","steps":["trace[833665300] 'process raft request' (duration: 76.626132ms)","trace[833665300] 'compare' (duration: 43.105932ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:29.726308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867347242014669,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:53:29.902585Z","caller":"traceutil/trace.go:171","msg":"trace[1486279523] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"677.081379ms","start":"2026-02-11T01:53:29.225481Z","end":"2026-02-11T01:53:29.902563Z","steps":["trace[1486279523] 'read index received' (duration: 676.882804ms)","trace[1486279523] 'applied index is now lower than readState.Index' (duration: 197.645µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:53:29.902973Z","caller":"traceutil/trace.go:171","msg":"trace[792242849] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"746.339909ms","start":"2026-02-11T01:53:29.156611Z","end":"2026-02-11T01:53:29.902951Z","steps":["trace[792242849] 'process raft request' (duration: 745.818537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:29.903258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:29.156603Z","time spent":"746.559114ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:53:29.904039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.568154ms","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-11T01:53:29.904085Z","caller":"traceutil/trace.go:171","msg":"trace[656125357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"678.664516ms","start":"2026-02-11T01:53:29.225409Z","end":"2026-02-11T01:53:29.904074Z","steps":["trace[656125357] 'agreement among raft nodes before linearized reading' (duration: 678.528073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:29.904211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:29.225394Z","time spent":"678.752858ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:29.904517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.210142ms","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-11T01:53:29.90463Z","caller":"traceutil/trace.go:171","msg":"trace[6750472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"643.361895ms","start":"2026-02-11T01:53:29.261258Z","end":"2026-02-11T01:53:29.90462Z","steps":["trace[6750472] 'agreement among raft nodes before linearized reading' (duration: 643.17592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:29.904685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:29.261247Z","time spent":"643.406326ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:53:29.905462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.587063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:53:29.905506Z","caller":"traceutil/trace.go:171","msg":"trace[1121701351] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1007; }","duration":"576.656835ms","start":"2026-02-11T01:53:29.328839Z","end":"2026-02-11T01:53:29.905496Z","steps":["trace[1121701351] 'agreement among raft nodes before linearized reading' (duration: 576.537332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:29.905536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:29.328828Z","time spent":"576.699906ms","remote":"127.0.0.1:49856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:53:29.90628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.279075ms","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-11T01:53:29.906323Z","caller":"traceutil/trace.go:171","msg":"trace[1794375905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"619.345457ms","start":"2026-02-11T01:53:29.286968Z","end":"2026-02-11T01:53:29.906314Z","steps":["trace[1794375905] 'agreement among raft nodes before linearized reading' (duration: 619.201034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:29.906446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:53:29.286943Z","time spent":"619.464139ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-11T01:53:32.091266Z","caller":"traceutil/trace.go:171","msg":"trace[48352382] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"221.912908ms","start":"2026-02-11T01:53:31.869318Z","end":"2026-02-11T01:53:32.091231Z","steps":["trace[48352382] 'process raft request' (duration: 221.197241ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:32.092359Z","caller":"traceutil/trace.go:171","msg":"trace[1646893036] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1060; }","duration":"178.214323ms","start":"2026-02-11T01:53:31.914128Z","end":"2026-02-11T01:53:32.092342Z","steps":["trace[1646893036] 'read index received' (duration: 178.208283ms)","trace[1646893036] 'applied index is now lower than readState.Index' (duration: 4.7µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:53:32.092917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.840119ms","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-11T01:53:32.093011Z","caller":"traceutil/trace.go:171","msg":"trace[1290368616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"117.981962ms","start":"2026-02-11T01:53:31.974971Z","end":"2026-02-11T01:53:32.092953Z","steps":["trace[1290368616] 'agreement among raft nodes before linearized reading' (duration: 117.553352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:32.093579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.40616ms","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-11T01:53:32.093623Z","caller":"traceutil/trace.go:171","msg":"trace[1971823735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"179.489612ms","start":"2026-02-11T01:53:31.914122Z","end":"2026-02-11T01:53:32.093612Z","steps":["trace[1971823735] 'agreement among raft nodes before linearized reading' (duration: 179.184145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:32.094163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.889704ms","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-11T01:53:32.094228Z","caller":"traceutil/trace.go:171","msg":"trace[823211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"118.990756ms","start":"2026-02-11T01:53:31.975226Z","end":"2026-02-11T01:53:32.094217Z","steps":["trace[823211] 'agreement among raft nodes before linearized reading' (duration: 118.834753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:53:32.094643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.481677ms","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-11T01:53:32.094707Z","caller":"traceutil/trace.go:171","msg":"trace[1236323116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"119.536179ms","start":"2026-02-11T01:53:31.975139Z","end":"2026-02-11T01:53:32.094676Z","steps":["trace[1236323116] 'agreement among raft nodes before linearized reading' (duration: 119.290333ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:32.286335Z","caller":"traceutil/trace.go:171","msg":"trace[1539671298] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"173.061503ms","start":"2026-02-11T01:53:32.113251Z","end":"2026-02-11T01:53:32.286312Z","steps":["trace[1539671298] 'process raft request' (duration: 172.658154ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:32.286826Z","caller":"traceutil/trace.go:171","msg":"trace[443297968] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"169.346447ms","start":"2026-02-11T01:53:32.117449Z","end":"2026-02-11T01:53:32.286796Z","steps":["trace[443297968] 'process raft request' (duration: 168.507668ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:32.286884Z","caller":"traceutil/trace.go:171","msg":"trace[1741349580] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"175.104291ms","start":"2026-02-11T01:53:32.111768Z","end":"2026-02-11T01:53:32.286872Z","steps":["trace[1741349580] 'process raft request' (duration: 170.160126ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:32.287099Z","caller":"traceutil/trace.go:171","msg":"trace[97253281] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"169.571932ms","start":"2026-02-11T01:53:32.117514Z","end":"2026-02-11T01:53:32.287086Z","steps":["trace[97253281] 'process raft request' (duration: 168.496447ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:53:39.117373Z","caller":"traceutil/trace.go:171","msg":"trace[1075098917] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"100.198299ms","start":"2026-02-11T01:53:39.01715Z","end":"2026-02-11T01:53:39.117348Z","steps":["trace[1075098917] 'process raft request' (duration: 60.985688ms)","trace[1075098917] 'compare' (duration: 38.659699ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:56:41.701239Z","caller":"traceutil/trace.go:171","msg":"trace[1884592311] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2026; }","duration":"210.07313ms","start":"2026-02-11T01:56:41.491145Z","end":"2026-02-11T01:56:41.701218Z","steps":["trace[1884592311] 'read index received' (duration: 155.512883ms)","trace[1884592311] 'applied index is now lower than readState.Index' (duration: 54.558997ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:56:41.701465Z","caller":"traceutil/trace.go:171","msg":"trace[971143009] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"236.259607ms","start":"2026-02-11T01:56:41.465193Z","end":"2026-02-11T01:56:41.701452Z","steps":["trace[971143009] 'process raft request' (duration: 181.480085ms)","trace[971143009] 'compare' (duration: 54.350042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:56:41.701662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.50086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-11T01:56:41.701688Z","caller":"traceutil/trace.go:171","msg":"trace[690208656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1942; }","duration":"210.563131ms","start":"2026-02-11T01:56:41.491119Z","end":"2026-02-11T01:56:41.701682Z","steps":["trace[690208656] 'agreement among raft nodes before linearized reading' (duration: 210.460358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:56:42.52775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.341642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:56:42.528353Z","caller":"traceutil/trace.go:171","msg":"trace[1451800928] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2028; }","duration":"712.520989ms","start":"2026-02-11T01:56:41.815815Z","end":"2026-02-11T01:56:42.528336Z","steps":["trace[1451800928] 'read index received' (duration: 34.483151ms)","trace[1451800928] 'applied index is now lower than readState.Index' (duration: 678.036938ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:56:42.528826Z","caller":"traceutil/trace.go:171","msg":"trace[1163793244] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"815.925131ms","start":"2026-02-11T01:56:41.712874Z","end":"2026-02-11T01:56:42.5288Z","steps":["trace[1163793244] 'process raft request' (duration: 137.467673ms)","trace[1163793244] 'compare' (duration: 676.761879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:56:42.529264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:56:41.712869Z","time spent":"816.351471ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:56:42.529939Z","caller":"traceutil/trace.go:171","msg":"trace[2098380451] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"816.030854ms","start":"2026-02-11T01:56:41.713893Z","end":"2026-02-11T01:56:42.529924Z","steps":["trace[2098380451] 'process raft request' (duration: 814.347345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:56:42.530239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:56:41.713883Z","time spent":"816.315701ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:56:42.530579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.760841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:56:42.531044Z","caller":"traceutil/trace.go:171","msg":"trace[257554101] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1945; }","duration":"715.245153ms","start":"2026-02-11T01:56:41.815785Z","end":"2026-02-11T01:56:42.531031Z","steps":["trace[257554101] 'agreement among raft nodes before linearized reading' (duration: 714.755851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:56:42.531369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:56:41.815772Z","time spent":"715.58187ms","remote":"127.0.0.1:49908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-11T01:56:42.531794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.612417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:56:42.531984Z","caller":"traceutil/trace.go:171","msg":"trace[1537423921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1945; }","duration":"472.833863ms","start":"2026-02-11T01:56:42.059135Z","end":"2026-02-11T01:56:42.531969Z","steps":["trace[1537423921] 'agreement among raft nodes before linearized reading' (duration: 472.628078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:56:42.532146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:56:42.059116Z","time spent":"473.016716ms","remote":"127.0.0.1:49738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}