{"level":"warn","ts":"2026-02-14T01:54:07.921739Z","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-14T01:54:07.921969Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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://162.253.55.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-14T01:54:07.922095Z","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-14T01:54:07.922161Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-14T01:54:07.922213Z","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-14T01:54:07.922869Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-02-14T01:54:07.922999Z","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://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-14T01:54:07.926179Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.923549ms"} {"level":"info","ts":"2026-02-14T01:54:07.931022Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-02-14T01:54:07.931163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-02-14T01:54:07.931257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-02-14T01:54:07.931298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-14T01:54:07.931646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-02-14T01:54:07.931744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-02-14T01:54:07.941914Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-14T01:54:07.946423Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-14T01:54:07.95073Z","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-14T01:54:07.960261Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-14T01:54:07.962083Z","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-14T01:54:07.96239Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-14T01:54:07.962518Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-14T01:54:07.96265Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-14T01:54:07.963919Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-14T01:54:07.962756Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-14T01:54:07.962855Z","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-14T01:54:07.963068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-02-14T01:54:07.964234Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-14T01:54:07.964134Z","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-14T01:54:07.96433Z","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-14T01:54:08.735524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-02-14T01:54:08.735586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-14T01:54:08.735601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-02-14T01:54:08.735611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-02-14T01:54:08.735616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-14T01:54:08.735624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-02-14T01:54:08.73563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-14T01:54:08.738769Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T01:54:08.742026Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-02-14T01:54:08.74213Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T01:54:08.742361Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-14T01:54:08.742514Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-14T01:54:08.742525Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-14T01:54:08.743783Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-02-14T01:54:08.74482Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-14T01:54:08.757559Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T01:54:08.757663Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T01:54:08.757686Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-14T01:54:11.071705Z","caller":"traceutil/trace.go:171","msg":"trace[412792878] linearizableReadLoop","detail":"{readStateIndex:96; appliedIndex:95; }","duration":"150.522801ms","start":"2026-02-14T01:54:10.921163Z","end":"2026-02-14T01:54:11.071686Z","steps":["trace[412792878] 'read index received' (duration: 51.572991ms)","trace[412792878] 'applied index is now lower than readState.Index' (duration: 98.94921ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:11.072219Z","caller":"traceutil/trace.go:171","msg":"trace[36452189] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"174.007726ms","start":"2026-02-14T01:54:10.898195Z","end":"2026-02-14T01:54:11.072203Z","steps":["trace[36452189] 'process raft request' (duration: 74.596765ms)","trace[36452189] 'compare' (duration: 98.816397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:11.074125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.919728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.1893fa150ed23f5e\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-02-14T01:54:11.074181Z","caller":"traceutil/trace.go:171","msg":"trace[108887063] range","detail":"{range_begin:/registry/events/default/instance.1893fa150ed23f5e; range_end:; response_count:1; response_revision:92; }","duration":"153.021ms","start":"2026-02-14T01:54:10.921146Z","end":"2026-02-14T01:54:11.074167Z","steps":["trace[108887063] 'agreement among raft nodes before linearized reading' (duration: 150.634663ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:11.271163Z","caller":"traceutil/trace.go:171","msg":"trace[55956976] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"156.769988ms","start":"2026-02-14T01:54:11.114299Z","end":"2026-02-14T01:54:11.271069Z","steps":["trace[55956976] 'process raft request' (duration: 58.77752ms)","trace[55956976] 'compare' (duration: 97.909446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:11.737482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.462708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-14T01:54:11.737751Z","caller":"traceutil/trace.go:171","msg":"trace[1944996268] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"415.997819ms","start":"2026-02-14T01:54:11.321714Z","end":"2026-02-14T01:54:11.737712Z","steps":["trace[1944996268] 'process raft request' (duration: 171.770303ms)","trace[1944996268] 'compare' (duration: 243.367706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:11.737997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:11.321709Z","time spent":"416.173873ms","remote":"127.0.0.1:36154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:11.737584Z","caller":"traceutil/trace.go:171","msg":"trace[1864648823] linearizableReadLoop","detail":"{readStateIndex:101; appliedIndex:100; }","duration":"371.23672ms","start":"2026-02-14T01:54:11.366332Z","end":"2026-02-14T01:54:11.737569Z","steps":["trace[1864648823] 'read index received' (duration: 127.216069ms)","trace[1864648823] 'applied index is now lower than readState.Index' (duration: 244.01955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:11.738535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.194562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T01:54:11.738641Z","caller":"traceutil/trace.go:171","msg":"trace[93897969] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:97; }","duration":"372.326515ms","start":"2026-02-14T01:54:11.366295Z","end":"2026-02-14T01:54:11.738621Z","steps":["trace[93897969] 'agreement among raft nodes before linearized reading' (duration: 372.171882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.738735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:11.36628Z","time spent":"372.443569ms","remote":"127.0.0.1:36414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-02-14T01:54:11.739599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.132835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T01:54:11.739759Z","caller":"traceutil/trace.go:171","msg":"trace[351009919] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:97; }","duration":"373.310369ms","start":"2026-02-14T01:54:11.366436Z","end":"2026-02-14T01:54:11.739746Z","steps":["trace[351009919] 'agreement among raft nodes before linearized reading' (duration: 373.119114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.740069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:11.36643Z","time spent":"373.530274ms","remote":"127.0.0.1:36440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"warn","ts":"2026-02-14T01:54:11.741343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.372957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T01:54:11.741388Z","caller":"traceutil/trace.go:171","msg":"trace[1143446734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:97; }","duration":"328.463489ms","start":"2026-02-14T01:54:11.412913Z","end":"2026-02-14T01:54:11.741377Z","steps":["trace[1143446734] 'agreement among raft nodes before linearized reading' (duration: 328.391747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:11.74201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:11.412902Z","time spent":"328.683424ms","remote":"127.0.0.1:36072","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-14T01:54:11.742287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.32106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-14T01:54:11.742392Z","caller":"traceutil/trace.go:171","msg":"trace[523163917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:97; }","duration":"198.477464ms","start":"2026-02-14T01:54:11.543902Z","end":"2026-02-14T01:54:11.742379Z","steps":["trace[523163917] 'agreement among raft nodes before linearized reading' (duration: 196.446546ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:14.273567Z","caller":"traceutil/trace.go:171","msg":"trace[1701894678] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:279; }","duration":"119.743451ms","start":"2026-02-14T01:54:14.153803Z","end":"2026-02-14T01:54:14.273546Z","steps":["trace[1701894678] 'read index received' (duration: 97.657151ms)","trace[1701894678] 'applied index is now lower than readState.Index' (duration: 22.08546ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:14.273761Z","caller":"traceutil/trace.go:171","msg":"trace[1789323739] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"129.694286ms","start":"2026-02-14T01:54:14.144053Z","end":"2026-02-14T01:54:14.273747Z","steps":["trace[1789323739] 'process raft request' (duration: 107.399941ms)","trace[1789323739] 'compare' (duration: 21.937837ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:14.274132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.210304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3705"} {"level":"info","ts":"2026-02-14T01:54:14.274166Z","caller":"traceutil/trace.go:171","msg":"trace[236798821] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:276; }","duration":"123.263085ms","start":"2026-02-14T01:54:14.150893Z","end":"2026-02-14T01:54:14.274156Z","steps":["trace[236798821] 'agreement among raft nodes before linearized reading' (duration: 123.171463ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:14.274296Z","caller":"traceutil/trace.go:171","msg":"trace[1868306361] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"121.918783ms","start":"2026-02-14T01:54:14.152367Z","end":"2026-02-14T01:54:14.274286Z","steps":["trace[1868306361] 'process raft request' (duration: 121.139084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:14.274607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.072853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-14T01:54:14.274636Z","caller":"traceutil/trace.go:171","msg":"trace[1440554630] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:276; }","duration":"124.112285ms","start":"2026-02-14T01:54:14.150516Z","end":"2026-02-14T01:54:14.274628Z","steps":["trace[1440554630] 'agreement among raft nodes before linearized reading' (duration: 124.059844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:14.277112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.483237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:14.277154Z","caller":"traceutil/trace.go:171","msg":"trace[1539698749] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:276; }","duration":"128.655251ms","start":"2026-02-14T01:54:14.148487Z","end":"2026-02-14T01:54:14.277142Z","steps":["trace[1539698749] 'agreement among raft nodes before linearized reading' (duration: 125.141588ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:14.285245Z","caller":"traceutil/trace.go:171","msg":"trace[999227716] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"126.4664ms","start":"2026-02-14T01:54:14.158771Z","end":"2026-02-14T01:54:14.285238Z","steps":["trace[999227716] 'process raft request' (duration: 126.284666ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:21.401325Z","caller":"traceutil/trace.go:171","msg":"trace[200406315] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"206.146677ms","start":"2026-02-14T01:54:21.19509Z","end":"2026-02-14T01:54:21.401237Z","steps":["trace[200406315] 'process raft request' (duration: 206.019234ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:27.844619Z","caller":"traceutil/trace.go:171","msg":"trace[992191405] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"818.153706ms","start":"2026-02-14T01:54:27.02644Z","end":"2026-02-14T01:54:27.844594Z","steps":["trace[992191405] 'process raft request' (duration: 818.007823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:27.844984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:27.026429Z","time spent":"818.361831ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:28.939443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.238216ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T01:54:28.939567Z","caller":"traceutil/trace.go:171","msg":"trace[1803221772] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:470; }","duration":"1.378409557s","start":"2026-02-14T01:54:27.56114Z","end":"2026-02-14T01:54:28.93955Z","steps":["trace[1803221772] 'read index received' (duration: 283.653003ms)","trace[1803221772] 'applied index is now lower than readState.Index' (duration: 1.094755144s)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:28.939689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37853814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:28.939723Z","caller":"traceutil/trace.go:171","msg":"trace[888304289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"1.378602631s","start":"2026-02-14T01:54:27.561111Z","end":"2026-02-14T01:54:28.939714Z","steps":["trace[888304289] 'agreement among raft nodes before linearized reading' (duration: 1.3785369s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:28.939758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:27.561095Z","time spent":"1.378653632s","remote":"127.0.0.1:36072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:54:28.939823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.856131ms","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-14T01:54:28.93988Z","caller":"traceutil/trace.go:171","msg":"trace[1443822008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:455; }","duration":"548.952574ms","start":"2026-02-14T01:54:28.390912Z","end":"2026-02-14T01:54:28.939864Z","steps":["trace[1443822008] 'agreement among raft nodes before linearized reading' (duration: 548.818651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:28.939908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:28.390896Z","time spent":"549.007265ms","remote":"127.0.0.1:36344","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-14T01:54:28.940098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.768948ms","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-14T01:54:28.940112Z","caller":"traceutil/trace.go:171","msg":"trace[542492461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"191.809099ms","start":"2026-02-14T01:54:28.748298Z","end":"2026-02-14T01:54:28.940108Z","steps":["trace[542492461] 'agreement among raft nodes before linearized reading' (duration: 191.782098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:28.940194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.563111ms","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-14T01:54:28.940206Z","caller":"traceutil/trace.go:171","msg":"trace[60349173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"224.600592ms","start":"2026-02-14T01:54:28.715602Z","end":"2026-02-14T01:54:28.940202Z","steps":["trace[60349173] 'agreement among raft nodes before linearized reading' (duration: 224.577451ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:29.114047Z","caller":"traceutil/trace.go:171","msg":"trace[202009148] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"164.005185ms","start":"2026-02-14T01:54:28.950024Z","end":"2026-02-14T01:54:29.114029Z","steps":["trace[202009148] 'process raft request' (duration: 163.975784ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:29.114355Z","caller":"traceutil/trace.go:171","msg":"trace[1076490405] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"165.217612ms","start":"2026-02-14T01:54:28.94913Z","end":"2026-02-14T01:54:29.114348Z","steps":["trace[1076490405] 'process raft request' (duration: 164.771252ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:34.842725Z","caller":"traceutil/trace.go:171","msg":"trace[919977791] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"130.513555ms","start":"2026-02-14T01:54:34.712195Z","end":"2026-02-14T01:54:34.842708Z","steps":["trace[919977791] 'process raft request' (duration: 130.437103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.54964Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.647327252s","expected-duration":"1s"} {"level":"info","ts":"2026-02-14T01:54:41.549885Z","caller":"traceutil/trace.go:171","msg":"trace[946454255] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.647617068s","start":"2026-02-14T01:54:39.902253Z","end":"2026-02-14T01:54:41.54987Z","steps":["trace[946454255] 'process raft request' (duration: 1.647521696s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.549992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:39.902244Z","time spent":"1.64769352s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:41.55025Z","caller":"traceutil/trace.go:171","msg":"trace[444652693] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:536; }","duration":"465.59529ms","start":"2026-02-14T01:54:41.084647Z","end":"2026-02-14T01:54:41.550242Z","steps":["trace[444652693] 'read index received' (duration: 465.59266ms)","trace[444652693] 'applied index is now lower than readState.Index' (duration: 2.18µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:41.550302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.655352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-14T01:54:41.550321Z","caller":"traceutil/trace.go:171","msg":"trace[512777369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"465.702822ms","start":"2026-02-14T01:54:41.084612Z","end":"2026-02-14T01:54:41.550315Z","steps":["trace[512777369] 'agreement among raft nodes before linearized reading' (duration: 465.665471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.55034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.084586Z","time spent":"465.748823ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T01:54:41.552937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.08032ms","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-14T01:54:41.55298Z","caller":"traceutil/trace.go:171","msg":"trace[1794482785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"286.150081ms","start":"2026-02-14T01:54:41.266821Z","end":"2026-02-14T01:54:41.552971Z","steps":["trace[1794482785] 'agreement among raft nodes before linearized reading' (duration: 286.07907ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:41.553117Z","caller":"traceutil/trace.go:171","msg":"trace[201896570] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"413.200636ms","start":"2026-02-14T01:54:41.13991Z","end":"2026-02-14T01:54:41.55311Z","steps":["trace[201896570] 'process raft request' (duration: 412.927249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:41.553174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:41.139891Z","time spent":"413.253777ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:41.553258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.642468ms","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-14T01:54:41.553275Z","caller":"traceutil/trace.go:171","msg":"trace[576405923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"276.679359ms","start":"2026-02-14T01:54:41.27659Z","end":"2026-02-14T01:54:41.55327Z","steps":["trace[576405923] 'agreement among raft nodes before linearized reading' (duration: 276.648548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.062501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058068684816485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T01:54:44.086329Z","caller":"traceutil/trace.go:171","msg":"trace[1509965871] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"530.044707ms","start":"2026-02-14T01:54:43.556266Z","end":"2026-02-14T01:54:44.086311Z","steps":["trace[1509965871] 'process raft request' (duration: 529.913114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.086431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:43.556257Z","time spent":"530.110938ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:44.086607Z","caller":"traceutil/trace.go:171","msg":"trace[1954164770] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:543; }","duration":"524.65894ms","start":"2026-02-14T01:54:43.561941Z","end":"2026-02-14T01:54:44.0866Z","steps":["trace[1954164770] 'read index received' (duration: 524.65604ms)","trace[1954164770] 'applied index is now lower than readState.Index' (duration: 2.38µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:44.086856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.857418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-14T01:54:44.086887Z","caller":"traceutil/trace.go:171","msg":"trace[360712699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"505.898918ms","start":"2026-02-14T01:54:43.580979Z","end":"2026-02-14T01:54:44.086878Z","steps":["trace[360712699] 'agreement among raft nodes before linearized reading' (duration: 505.663103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.08691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:43.580974Z","time spent":"505.930939ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-14T01:54:44.087046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.076631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:44.087068Z","caller":"traceutil/trace.go:171","msg":"trace[636963389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"525.132142ms","start":"2026-02-14T01:54:43.561929Z","end":"2026-02-14T01:54:44.087061Z","steps":["trace[636963389] 'agreement among raft nodes before linearized reading' (duration: 525.07633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.087085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:43.561924Z","time spent":"525.157352ms","remote":"127.0.0.1:36072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:54:44.087189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.544534ms","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-14T01:54:44.087226Z","caller":"traceutil/trace.go:171","msg":"trace[228837155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"478.580574ms","start":"2026-02-14T01:54:43.608639Z","end":"2026-02-14T01:54:44.08722Z","steps":["trace[228837155] 'agreement among raft nodes before linearized reading' (duration: 478.527443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.087248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:43.608608Z","time spent":"478.634066ms","remote":"127.0.0.1:36344","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-14T01:54:44.087337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.902392ms","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-14T01:54:44.087381Z","caller":"traceutil/trace.go:171","msg":"trace[2130793604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"478.988044ms","start":"2026-02-14T01:54:43.608387Z","end":"2026-02-14T01:54:44.087375Z","steps":["trace[2130793604] 'agreement among raft nodes before linearized reading' (duration: 478.932743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:44.087442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:43.608366Z","time spent":"479.069866ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:54:45.305947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.379558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:54:45.306186Z","caller":"traceutil/trace.go:171","msg":"trace[2051182921] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.212538398s","start":"2026-02-14T01:54:44.093632Z","end":"2026-02-14T01:54:45.30617Z","steps":["trace[2051182921] 'process raft request' (duration: 1.212493587s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:45.306495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:44.093625Z","time spent":"1.212596299s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:45.306548Z","caller":"traceutil/trace.go:171","msg":"trace[312703473] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.214792941s","start":"2026-02-14T01:54:44.091728Z","end":"2026-02-14T01:54:45.306521Z","steps":["trace[312703473] 'process raft request' (duration: 326.762759ms)","trace[312703473] 'compare' (duration: 886.321622ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:45.306695Z","caller":"traceutil/trace.go:171","msg":"trace[884847828] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"1.214785911s","start":"2026-02-14T01:54:44.091897Z","end":"2026-02-14T01:54:45.306683Z","steps":["trace[884847828] 'read index received' (duration: 326.611305ms)","trace[884847828] 'applied index is now lower than readState.Index' (duration: 888.173256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:45.306697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:44.091714Z","time spent":"1.214895973s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:54:45.30678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214873483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:45.306809Z","caller":"traceutil/trace.go:171","msg":"trace[1272968587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"1.214923215s","start":"2026-02-14T01:54:44.091877Z","end":"2026-02-14T01:54:45.3068Z","steps":["trace[1272968587] 'agreement among raft nodes before linearized reading' (duration: 1.214847393s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:45.306839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:44.091869Z","time spent":"1.214963806s","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T01:54:45.30696Z","caller":"traceutil/trace.go:171","msg":"trace[306748968] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.215002076s","start":"2026-02-14T01:54:44.091948Z","end":"2026-02-14T01:54:45.30695Z","steps":["trace[306748968] 'process raft request' (duration: 1.214102305s)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:45.307016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:44.091943Z","time spent":"1.215037227s","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:45.341822Z","caller":"traceutil/trace.go:171","msg":"trace[1014317766] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"482.99013ms","start":"2026-02-14T01:54:44.858812Z","end":"2026-02-14T01:54:45.341802Z","steps":["trace[1014317766] 'process raft request' (duration: 482.902678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:45.341945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:44.858788Z","time spent":"483.093362ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:49.506706Z","caller":"traceutil/trace.go:171","msg":"trace[1904948298] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"175.494375ms","start":"2026-02-14T01:54:49.331189Z","end":"2026-02-14T01:54:49.506683Z","steps":["trace[1904948298] 'read index received' (duration: 175.245399ms)","trace[1904948298] 'applied index is now lower than readState.Index' (duration: 247.786µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:49.507092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.881204ms","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-14T01:54:49.50718Z","caller":"traceutil/trace.go:171","msg":"trace[2069656947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"175.994377ms","start":"2026-02-14T01:54:49.331152Z","end":"2026-02-14T01:54:49.507146Z","steps":["trace[2069656947] 'agreement among raft nodes before linearized reading' (duration: 175.778882ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:49.507325Z","caller":"traceutil/trace.go:171","msg":"trace[1801886253] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"178.531187ms","start":"2026-02-14T01:54:49.328781Z","end":"2026-02-14T01:54:49.507312Z","steps":["trace[1801886253] 'process raft request' (duration: 177.707437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:49.507518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.473624ms","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-14T01:54:49.507764Z","caller":"traceutil/trace.go:171","msg":"trace[982310078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"175.516096ms","start":"2026-02-14T01:54:49.332021Z","end":"2026-02-14T01:54:49.507537Z","steps":["trace[982310078] 'agreement among raft nodes before linearized reading' (duration: 175.401843ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:50.614921Z","caller":"traceutil/trace.go:171","msg":"trace[370498369] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"357.732869ms","start":"2026-02-14T01:54:50.257165Z","end":"2026-02-14T01:54:50.614898Z","steps":["trace[370498369] 'read index received' (duration: 357.562845ms)","trace[370498369] 'applied index is now lower than readState.Index' (duration: 169.144µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:50.615075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.888632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9dpq5\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-14T01:54:50.615107Z","caller":"traceutil/trace.go:171","msg":"trace[242939018] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9dpq5; range_end:; response_count:1; response_revision:540; }","duration":"357.962703ms","start":"2026-02-14T01:54:50.257135Z","end":"2026-02-14T01:54:50.615098Z","steps":["trace[242939018] 'agreement among raft nodes before linearized reading' (duration: 357.847311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:50.615142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:50.257122Z","time spent":"358.012605ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-9dpq5\" "} {"level":"info","ts":"2026-02-14T01:54:50.615446Z","caller":"traceutil/trace.go:171","msg":"trace[1011677978] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"504.725001ms","start":"2026-02-14T01:54:50.110708Z","end":"2026-02-14T01:54:50.615433Z","steps":["trace[1011677978] 'process raft request' (duration: 504.078356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:50.616414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:50.110697Z","time spent":"504.775173ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:54:50.839745Z","caller":"traceutil/trace.go:171","msg":"trace[1827084532] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"211.36949ms","start":"2026-02-14T01:54:50.628351Z","end":"2026-02-14T01:54:50.839721Z","steps":["trace[1827084532] 'process raft request' (duration: 180.608445ms)","trace[1827084532] 'compare' (duration: 30.665502ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:52.888527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.662364ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-14T01:54:52.888727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:52.448435Z","time spent":"440.288173ms","remote":"127.0.0.1:36098","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-14T01:54:52.888826Z","caller":"traceutil/trace.go:171","msg":"trace[1583701972] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"326.246766ms","start":"2026-02-14T01:54:52.562561Z","end":"2026-02-14T01:54:52.888808Z","steps":["trace[1583701972] 'read index received' (duration: 187.63056ms)","trace[1583701972] 'applied index is now lower than readState.Index' (duration: 138.503164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:54:52.889047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.487092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:54:52.889589Z","caller":"traceutil/trace.go:171","msg":"trace[1193861197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"327.064446ms","start":"2026-02-14T01:54:52.56251Z","end":"2026-02-14T01:54:52.889575Z","steps":["trace[1193861197] 'agreement among raft nodes before linearized reading' (duration: 326.485613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:52.889687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:54:52.562472Z","time spent":"327.199499ms","remote":"127.0.0.1:36072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:54:52.889998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.108717ms","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-14T01:54:52.890043Z","caller":"traceutil/trace.go:171","msg":"trace[1901866429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"268.212929ms","start":"2026-02-14T01:54:52.621819Z","end":"2026-02-14T01:54:52.890031Z","steps":["trace[1901866429] 'agreement among raft nodes before linearized reading' (duration: 268.074066ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:55.785496Z","caller":"traceutil/trace.go:171","msg":"trace[390773562] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"237.086607ms","start":"2026-02-14T01:54:55.548396Z","end":"2026-02-14T01:54:55.785483Z","steps":["trace[390773562] 'read index received' (duration: 236.976144ms)","trace[390773562] 'applied index is now lower than readState.Index' (duration: 109.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:55.785566Z","caller":"traceutil/trace.go:171","msg":"trace[1632414967] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"238.644543ms","start":"2026-02-14T01:54:55.546915Z","end":"2026-02-14T01:54:55.78556Z","steps":["trace[1632414967] 'process raft request' (duration: 238.431188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:55.785781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.339062ms","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-14T01:54:55.7858Z","caller":"traceutil/trace.go:171","msg":"trace[1235947885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"237.419304ms","start":"2026-02-14T01:54:55.548373Z","end":"2026-02-14T01:54:55.785793Z","steps":["trace[1235947885] 'agreement among raft nodes before linearized reading' (duration: 237.311151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:55.78587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.425975ms","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-14T01:54:55.785881Z","caller":"traceutil/trace.go:171","msg":"trace[1268674310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"237.450915ms","start":"2026-02-14T01:54:55.548426Z","end":"2026-02-14T01:54:55.785877Z","steps":["trace[1268674310] 'agreement among raft nodes before linearized reading' (duration: 237.411854ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:57.822582Z","caller":"traceutil/trace.go:171","msg":"trace[158411822] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"182.92367ms","start":"2026-02-14T01:54:57.639643Z","end":"2026-02-14T01:54:57.822566Z","steps":["trace[158411822] 'read index received' (duration: 182.727096ms)","trace[158411822] 'applied index is now lower than readState.Index' (duration: 195.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:54:57.822828Z","caller":"traceutil/trace.go:171","msg":"trace[1539949130] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"197.539584ms","start":"2026-02-14T01:54:57.62528Z","end":"2026-02-14T01:54:57.82282Z","steps":["trace[1539949130] 'process raft request' (duration: 197.178276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:57.823089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.417052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-14T01:54:57.82312Z","caller":"traceutil/trace.go:171","msg":"trace[2095425030] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:638; }","duration":"183.473203ms","start":"2026-02-14T01:54:57.639639Z","end":"2026-02-14T01:54:57.823112Z","steps":["trace[2095425030] 'agreement among raft nodes before linearized reading' (duration: 183.389871ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:54:57.833998Z","caller":"traceutil/trace.go:171","msg":"trace[1762249604] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"175.477634ms","start":"2026-02-14T01:54:57.658512Z","end":"2026-02-14T01:54:57.83399Z","steps":["trace[1762249604] 'process raft request' (duration: 174.894671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:57.837238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.845328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-14T01:54:57.837294Z","caller":"traceutil/trace.go:171","msg":"trace[1291272339] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:639; }","duration":"196.90771ms","start":"2026-02-14T01:54:57.640363Z","end":"2026-02-14T01:54:57.837271Z","steps":["trace[1291272339] 'agreement among raft nodes before linearized reading' (duration: 196.812157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:57.837451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.045303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-14T01:54:57.837467Z","caller":"traceutil/trace.go:171","msg":"trace[31104430] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:639; }","duration":"197.078853ms","start":"2026-02-14T01:54:57.640383Z","end":"2026-02-14T01:54:57.837461Z","steps":["trace[31104430] 'agreement among raft nodes before linearized reading' (duration: 197.038842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:54:57.844076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.614466ms","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-14T01:54:57.844112Z","caller":"traceutil/trace.go:171","msg":"trace[2058713642] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:639; }","duration":"118.073742ms","start":"2026-02-14T01:54:57.72603Z","end":"2026-02-14T01:54:57.844104Z","steps":["trace[2058713642] 'agreement among raft nodes before linearized reading' (duration: 110.553284ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:02.187399Z","caller":"traceutil/trace.go:171","msg":"trace[1305170581] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"163.692056ms","start":"2026-02-14T01:55:02.023698Z","end":"2026-02-14T01:55:02.18739Z","steps":["trace[1305170581] 'process raft request' (duration: 163.201605ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:02.187621Z","caller":"traceutil/trace.go:171","msg":"trace[1754205041] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"144.389332ms","start":"2026-02-14T01:55:02.042657Z","end":"2026-02-14T01:55:02.187046Z","steps":["trace[1754205041] 'read index received' (duration: 144.200158ms)","trace[1754205041] 'applied index is now lower than readState.Index' (duration: 188.694µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:02.187661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.003196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:02.187691Z","caller":"traceutil/trace.go:171","msg":"trace[1866177431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"145.064208ms","start":"2026-02-14T01:55:02.042622Z","end":"2026-02-14T01:55:02.187687Z","steps":["trace[1866177431] 'agreement among raft nodes before linearized reading' (duration: 145.014097ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:02.213278Z","caller":"traceutil/trace.go:171","msg":"trace[1605246873] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"170.548949ms","start":"2026-02-14T01:55:02.042714Z","end":"2026-02-14T01:55:02.213263Z","steps":["trace[1605246873] 'process raft request' (duration: 170.385665ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:02.213456Z","caller":"traceutil/trace.go:171","msg":"trace[1944060212] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"117.54342ms","start":"2026-02-14T01:55:02.095893Z","end":"2026-02-14T01:55:02.213436Z","steps":["trace[1944060212] 'process raft request' (duration: 117.339455ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:03.317749Z","caller":"traceutil/trace.go:171","msg":"trace[958183714] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"304.912034ms","start":"2026-02-14T01:55:03.012822Z","end":"2026-02-14T01:55:03.317734Z","steps":["trace[958183714] 'process raft request' (duration: 304.829272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:03.317843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.012813Z","time spent":"304.977775ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:03.848589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.736489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:03.848668Z","caller":"traceutil/trace.go:171","msg":"trace[129291984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"287.868893ms","start":"2026-02-14T01:55:03.560781Z","end":"2026-02-14T01:55:03.84865Z","steps":["trace[129291984] 'range keys from in-memory index tree' (duration: 287.681498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:03.848859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.034689ms","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-14T01:55:03.848904Z","caller":"traceutil/trace.go:171","msg":"trace[1150973164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:671; }","duration":"460.126712ms","start":"2026-02-14T01:55:03.388766Z","end":"2026-02-14T01:55:03.848893Z","steps":["trace[1150973164] 'range keys from in-memory index tree' (duration: 459.915467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:03.848926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.388751Z","time spent":"460.170432ms","remote":"127.0.0.1:36344","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":"info","ts":"2026-02-14T01:55:03.95562Z","caller":"traceutil/trace.go:171","msg":"trace[1493585555] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"102.032604ms","start":"2026-02-14T01:55:03.853571Z","end":"2026-02-14T01:55:03.955604Z","steps":["trace[1493585555] 'process raft request' (duration: 101.603823ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:04.90298Z","caller":"traceutil/trace.go:171","msg":"trace[67521651] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"944.211957ms","start":"2026-02-14T01:55:03.958723Z","end":"2026-02-14T01:55:04.902935Z","steps":["trace[67521651] 'process raft request' (duration: 943.886899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:04.903161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.958713Z","time spent":"944.344139ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:04.954311Z","caller":"traceutil/trace.go:171","msg":"trace[952883027] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:698; }","duration":"393.349087ms","start":"2026-02-14T01:55:04.560938Z","end":"2026-02-14T01:55:04.954287Z","steps":["trace[952883027] 'read index received' (duration: 342.018428ms)","trace[952883027] 'applied index is now lower than readState.Index' (duration: 51.329369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:04.95449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.524061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:04.954531Z","caller":"traceutil/trace.go:171","msg":"trace[1106447782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"393.611124ms","start":"2026-02-14T01:55:04.560908Z","end":"2026-02-14T01:55:04.954519Z","steps":["trace[1106447782] 'agreement among raft nodes before linearized reading' (duration: 393.46808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:04.954569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:04.560895Z","time spent":"393.667025ms","remote":"127.0.0.1:36072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T01:55:04.955567Z","caller":"traceutil/trace.go:171","msg":"trace[1728570950] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"994.710176ms","start":"2026-02-14T01:55:03.960763Z","end":"2026-02-14T01:55:04.955459Z","steps":["trace[1728570950] 'process raft request' (duration: 993.352154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:04.956169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.960755Z","time spent":"995.32185ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:04.956772Z","caller":"traceutil/trace.go:171","msg":"trace[90368320] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"995.922655ms","start":"2026-02-14T01:55:03.960836Z","end":"2026-02-14T01:55:04.956759Z","steps":["trace[90368320] 'process raft request' (duration: 993.409555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:04.957032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:03.960819Z","time spent":"996.136949ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:06.320092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.234534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:06.320171Z","caller":"traceutil/trace.go:171","msg":"trace[199057508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"758.357567ms","start":"2026-02-14T01:55:05.561794Z","end":"2026-02-14T01:55:06.320151Z","steps":["trace[199057508] 'range keys from in-memory index tree' (duration: 758.172813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:06.320212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:05.561772Z","time spent":"758.43173ms","remote":"127.0.0.1:36072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:55:06.321073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.610884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:06.32113Z","caller":"traceutil/trace.go:171","msg":"trace[1447930325] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"357.201586ms","start":"2026-02-14T01:55:05.963917Z","end":"2026-02-14T01:55:06.321119Z","steps":["trace[1447930325] 'read index received' (duration: 35.231µs)","trace[1447930325] 'applied index is now lower than readState.Index' (duration: 357.165375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:06.321286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.36624ms","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-14T01:55:06.321312Z","caller":"traceutil/trace.go:171","msg":"trace[1896711238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"357.430471ms","start":"2026-02-14T01:55:05.963873Z","end":"2026-02-14T01:55:06.321304Z","steps":["trace[1896711238] 'agreement among raft nodes before linearized reading' (duration: 357.282018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:06.32134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:05.963852Z","time spent":"357.481233ms","remote":"127.0.0.1:36344","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":"info","ts":"2026-02-14T01:55:06.321594Z","caller":"traceutil/trace.go:171","msg":"trace[1986437968] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"581.418579ms","start":"2026-02-14T01:55:05.740161Z","end":"2026-02-14T01:55:06.321579Z","steps":["trace[1986437968] 'process raft request' (duration: 139.239721ms)","trace[1986437968] 'compare' (duration: 441.246386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:06.321653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:05.74014Z","time spent":"581.48089ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:06.879827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.872273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:06.879922Z","caller":"traceutil/trace.go:171","msg":"trace[1836509035] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"317.748727ms","start":"2026-02-14T01:55:06.562156Z","end":"2026-02-14T01:55:06.879905Z","steps":["trace[1836509035] 'read index received' (duration: 30.971µs)","trace[1836509035] 'applied index is now lower than readState.Index' (duration: 317.716676ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:06.880001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.843549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:06.880025Z","caller":"traceutil/trace.go:171","msg":"trace[1785831535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"317.9015ms","start":"2026-02-14T01:55:06.562115Z","end":"2026-02-14T01:55:06.880017Z","steps":["trace[1785831535] 'agreement among raft nodes before linearized reading' (duration: 317.835419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:06.880057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:06.562096Z","time spent":"317.952251ms","remote":"127.0.0.1:36072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-14T01:55:06.880415Z","caller":"traceutil/trace.go:171","msg":"trace[1463175118] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"545.869611ms","start":"2026-02-14T01:55:06.334532Z","end":"2026-02-14T01:55:06.880401Z","steps":["trace[1463175118] 'process raft request' (duration: 111.337113ms)","trace[1463175118] 'compare' (duration: 433.676238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:06.880491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:06.334522Z","time spent":"545.921882ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:07.183584Z","caller":"traceutil/trace.go:171","msg":"trace[1974121578] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"207.323615ms","start":"2026-02-14T01:55:06.976245Z","end":"2026-02-14T01:55:07.183569Z","steps":["trace[1974121578] 'read index received' (duration: 207.157741ms)","trace[1974121578] 'applied index is now lower than readState.Index' (duration: 165.244µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:07.183813Z","caller":"traceutil/trace.go:171","msg":"trace[822432179] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"271.148389ms","start":"2026-02-14T01:55:06.912656Z","end":"2026-02-14T01:55:07.183805Z","steps":["trace[822432179] 'process raft request' (duration: 270.79007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:07.184008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.755405ms","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-14T01:55:07.184038Z","caller":"traceutil/trace.go:171","msg":"trace[2095098231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"207.827787ms","start":"2026-02-14T01:55:06.976203Z","end":"2026-02-14T01:55:07.184031Z","steps":["trace[2095098231] 'agreement among raft nodes before linearized reading' (duration: 207.732685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:07.184171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.259633ms","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-14T01:55:07.18419Z","caller":"traceutil/trace.go:171","msg":"trace[1990883690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"207.288594ms","start":"2026-02-14T01:55:06.976897Z","end":"2026-02-14T01:55:07.184185Z","steps":["trace[1990883690] 'agreement among raft nodes before linearized reading' (duration: 207.221542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:08.213561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.261659ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T01:55:08.650004Z","caller":"traceutil/trace.go:171","msg":"trace[29172697] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"263.757524ms","start":"2026-02-14T01:55:08.38623Z","end":"2026-02-14T01:55:08.649987Z","steps":["trace[29172697] 'process raft request' (duration: 263.454337ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:16.791811Z","caller":"traceutil/trace.go:171","msg":"trace[1516340646] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"319.362284ms","start":"2026-02-14T01:55:16.472427Z","end":"2026-02-14T01:55:16.791789Z","steps":["trace[1516340646] 'read index received' (duration: 319.227001ms)","trace[1516340646] 'applied index is now lower than readState.Index' (duration: 134.523µs)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:16.791923Z","caller":"traceutil/trace.go:171","msg":"trace[239523562] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"414.732331ms","start":"2026-02-14T01:55:16.377185Z","end":"2026-02-14T01:55:16.791917Z","steps":["trace[239523562] 'process raft request' (duration: 414.481646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:16.792009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:16.37716Z","time spent":"414.783453ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:16.792123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.646551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:16.792177Z","caller":"traceutil/trace.go:171","msg":"trace[2010933887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"232.720353ms","start":"2026-02-14T01:55:16.559442Z","end":"2026-02-14T01:55:16.792162Z","steps":["trace[2010933887] 'agreement among raft nodes before linearized reading' (duration: 232.636081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:16.792309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.877788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:16.792351Z","caller":"traceutil/trace.go:171","msg":"trace[1269784949] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:743; }","duration":"319.947518ms","start":"2026-02-14T01:55:16.472394Z","end":"2026-02-14T01:55:16.792342Z","steps":["trace[1269784949] 'agreement among raft nodes before linearized reading' (duration: 319.888087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:16.792374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:16.472379Z","time spent":"319.98933ms","remote":"127.0.0.1:36584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-14T01:55:17.440145Z","caller":"traceutil/trace.go:171","msg":"trace[1210174213] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"100.857816ms","start":"2026-02-14T01:55:17.33927Z","end":"2026-02-14T01:55:17.440128Z","steps":["trace[1210174213] 'process raft request' (duration: 86.238631ms)","trace[1210174213] 'compare' (duration: 14.548803ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:17.444614Z","caller":"traceutil/trace.go:171","msg":"trace[1426345655] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"103.034568ms","start":"2026-02-14T01:55:17.341571Z","end":"2026-02-14T01:55:17.444606Z","steps":["trace[1426345655] 'process raft request' (duration: 102.834963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:29.937862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.42865ms","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-14T01:55:29.937935Z","caller":"traceutil/trace.go:171","msg":"trace[1484185689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"430.519843ms","start":"2026-02-14T01:55:29.507383Z","end":"2026-02-14T01:55:29.937903Z","steps":["trace[1484185689] 'range keys from in-memory index tree' (duration: 430.302507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:29.937971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:29.507367Z","time spent":"430.588654ms","remote":"127.0.0.1:36344","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-14T01:55:29.938129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.122937ms","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-14T01:55:29.938144Z","caller":"traceutil/trace.go:171","msg":"trace[520881047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"383.162088ms","start":"2026-02-14T01:55:29.554976Z","end":"2026-02-14T01:55:29.938138Z","steps":["trace[520881047] 'range keys from in-memory index tree' (duration: 383.067337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:29.938156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:29.554962Z","time spent":"383.190619ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-14T01:55:29.938355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.243868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-14T01:55:29.938368Z","caller":"traceutil/trace.go:171","msg":"trace[2144861094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"337.276599ms","start":"2026-02-14T01:55:29.601088Z","end":"2026-02-14T01:55:29.938364Z","steps":["trace[2144861094] 'range keys from in-memory index tree' (duration: 337.188487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:29.93838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:29.601075Z","time spent":"337.302259ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T01:55:33.026384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.534338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:33.026544Z","caller":"traceutil/trace.go:171","msg":"trace[189829145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"464.719842ms","start":"2026-02-14T01:55:32.561807Z","end":"2026-02-14T01:55:33.026527Z","steps":["trace[189829145] 'range keys from in-memory index tree' (duration: 464.488017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:33.026611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:32.561795Z","time spent":"464.803214ms","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:55:33.56139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058068684817080,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-14T01:55:33.778698Z","caller":"traceutil/trace.go:171","msg":"trace[37256771] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"718.015679ms","start":"2026-02-14T01:55:33.06062Z","end":"2026-02-14T01:55:33.778636Z","steps":["trace[37256771] 'read index received' (duration: 717.833405ms)","trace[37256771] 'applied index is now lower than readState.Index' (duration: 181.074µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:33.779815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.46932ms","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-14T01:55:33.779882Z","caller":"traceutil/trace.go:171","msg":"trace[745430297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"346.592582ms","start":"2026-02-14T01:55:33.433272Z","end":"2026-02-14T01:55:33.779865Z","steps":["trace[745430297] 'agreement among raft nodes before linearized reading' (duration: 346.389428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:33.779928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:33.433254Z","time spent":"346.661344ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:55:33.780085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.925777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:33.78011Z","caller":"traceutil/trace.go:171","msg":"trace[552839538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"216.984487ms","start":"2026-02-14T01:55:33.563117Z","end":"2026-02-14T01:55:33.780102Z","steps":["trace[552839538] 'agreement among raft nodes before linearized reading' (duration: 216.941356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:33.780384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.991854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:55:33.780414Z","caller":"traceutil/trace.go:171","msg":"trace[2119668124] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:794; }","duration":"297.108367ms","start":"2026-02-14T01:55:33.483297Z","end":"2026-02-14T01:55:33.780406Z","steps":["trace[2119668124] 'agreement among raft nodes before linearized reading' (duration: 296.993705ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:33.780745Z","caller":"traceutil/trace.go:171","msg":"trace[288537610] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"757.119756ms","start":"2026-02-14T01:55:33.023615Z","end":"2026-02-14T01:55:33.780735Z","steps":["trace[288537610] 'process raft request' (duration: 754.897403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:33.780824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:33.023568Z","time spent":"757.201818ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:33.782948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.289731ms","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-14T01:55:33.783054Z","caller":"traceutil/trace.go:171","msg":"trace[660440052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"722.445875ms","start":"2026-02-14T01:55:33.060589Z","end":"2026-02-14T01:55:33.783035Z","steps":["trace[660440052] 'agreement among raft nodes before linearized reading' (duration: 718.533522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:33.783302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:33.06058Z","time spent":"722.63503ms","remote":"127.0.0.1:36344","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-14T01:55:34.162642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.654228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:34.162711Z","caller":"traceutil/trace.go:171","msg":"trace[1708271804] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"191.63326ms","start":"2026-02-14T01:55:33.971067Z","end":"2026-02-14T01:55:34.162701Z","steps":["trace[1708271804] 'read index received' (duration: 46.201µs)","trace[1708271804] 'applied index is now lower than readState.Index' (duration: 191.586399ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:34.163931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.736049ms","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-14T01:55:34.164063Z","caller":"traceutil/trace.go:171","msg":"trace[332879514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"155.905014ms","start":"2026-02-14T01:55:34.008143Z","end":"2026-02-14T01:55:34.164048Z","steps":["trace[332879514] 'agreement among raft nodes before linearized reading' (duration: 155.655768ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:34.164352Z","caller":"traceutil/trace.go:171","msg":"trace[649666412] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"374.798818ms","start":"2026-02-14T01:55:33.789543Z","end":"2026-02-14T01:55:34.164342Z","steps":["trace[649666412] 'process raft request' (duration: 88.390448ms)","trace[649666412] 'compare' (duration: 284.536615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:34.164506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:33.789535Z","time spent":"374.90381ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:34.164741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.668558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-14T01:55:34.165221Z","caller":"traceutil/trace.go:171","msg":"trace[424902551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"194.188841ms","start":"2026-02-14T01:55:33.971025Z","end":"2026-02-14T01:55:34.165214Z","steps":["trace[424902551] 'agreement among raft nodes before linearized reading' (duration: 193.666398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:34.164958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.149944ms","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-14T01:55:34.165559Z","caller":"traceutil/trace.go:171","msg":"trace[1673437892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"152.762839ms","start":"2026-02-14T01:55:34.012789Z","end":"2026-02-14T01:55:34.165552Z","steps":["trace[1673437892] 'agreement among raft nodes before linearized reading' (duration: 152.119673ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:34.525675Z","caller":"traceutil/trace.go:171","msg":"trace[1952472455] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:829; }","duration":"191.789055ms","start":"2026-02-14T01:55:34.333865Z","end":"2026-02-14T01:55:34.525654Z","steps":["trace[1952472455] 'read index received' (duration: 165.330941ms)","trace[1952472455] 'applied index is now lower than readState.Index' (duration: 26.457104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:34.52584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.924608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:34.525872Z","caller":"traceutil/trace.go:171","msg":"trace[1261127588] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:799; }","duration":"192.037661ms","start":"2026-02-14T01:55:34.333825Z","end":"2026-02-14T01:55:34.525863Z","steps":["trace[1261127588] 'agreement among raft nodes before linearized reading' (duration: 191.909208ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:34.525962Z","caller":"traceutil/trace.go:171","msg":"trace[2004655561] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"356.391237ms","start":"2026-02-14T01:55:34.169541Z","end":"2026-02-14T01:55:34.525932Z","steps":["trace[2004655561] 'process raft request' (duration: 329.727578ms)","trace[2004655561] 'compare' (duration: 26.012744ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:34.526103Z","caller":"traceutil/trace.go:171","msg":"trace[1552525762] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"355.185698ms","start":"2026-02-14T01:55:34.170905Z","end":"2026-02-14T01:55:34.526091Z","steps":["trace[1552525762] 'process raft request' (duration: 354.658826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:34.526131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:34.169534Z","time spent":"356.49046ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:34.5262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:34.170898Z","time spent":"355.234539ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:46.049259Z","caller":"traceutil/trace.go:171","msg":"trace[1429761805] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"156.15375ms","start":"2026-02-14T01:55:45.893089Z","end":"2026-02-14T01:55:46.049243Z","steps":["trace[1429761805] 'process raft request' (duration: 156.066318ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:46.728279Z","caller":"traceutil/trace.go:171","msg":"trace[66522028] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"122.494655ms","start":"2026-02-14T01:55:46.60577Z","end":"2026-02-14T01:55:46.728264Z","steps":["trace[66522028] 'read index received' (duration: 122.371332ms)","trace[66522028] 'applied index is now lower than readState.Index' (duration: 122.593µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:46.728401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.611318ms","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-14T01:55:46.728417Z","caller":"traceutil/trace.go:171","msg":"trace[2020306134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"122.655669ms","start":"2026-02-14T01:55:46.605755Z","end":"2026-02-14T01:55:46.728411Z","steps":["trace[2020306134] 'agreement among raft nodes before linearized reading' (duration: 122.567497ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:46.728485Z","caller":"traceutil/trace.go:171","msg":"trace[603126515] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"125.567778ms","start":"2026-02-14T01:55:46.602913Z","end":"2026-02-14T01:55:46.72848Z","steps":["trace[603126515] 'process raft request' (duration: 125.270271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:47.165964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.837821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:47.166058Z","caller":"traceutil/trace.go:171","msg":"trace[883471194] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"434.860527ms","start":"2026-02-14T01:55:46.73118Z","end":"2026-02-14T01:55:47.166041Z","steps":["trace[883471194] 'process raft request' (duration: 180.858263ms)","trace[883471194] 'compare' (duration: 253.635425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:47.166115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:46.731166Z","time spent":"434.922178ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:48.492224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.166174ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T01:55:48.492764Z","caller":"traceutil/trace.go:171","msg":"trace[175230081] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:918; }","duration":"440.90612ms","start":"2026-02-14T01:55:48.051841Z","end":"2026-02-14T01:55:48.492748Z","steps":["trace[175230081] 'read index received' (duration: 82.516026ms)","trace[175230081] 'applied index is now lower than readState.Index' (duration: 358.389154ms)"],"step_count":2} {"level":"info","ts":"2026-02-14T01:55:48.492839Z","caller":"traceutil/trace.go:171","msg":"trace[914688778] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"738.031808ms","start":"2026-02-14T01:55:47.7548Z","end":"2026-02-14T01:55:48.492831Z","steps":["trace[914688778] 'process raft request' (duration: 737.521696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:48.492909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:47.754778Z","time spent":"738.072999ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-14T01:55:48.609813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.703478ms","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-14T01:55:48.609911Z","caller":"traceutil/trace.go:171","msg":"trace[1569571839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:884; }","duration":"352.821211ms","start":"2026-02-14T01:55:48.257055Z","end":"2026-02-14T01:55:48.609876Z","steps":["trace[1569571839] 'agreement among raft nodes before linearized reading' (duration: 235.962162ms)","trace[1569571839] 'range keys from in-memory index tree' (duration: 116.665995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:48.609906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.308588ms","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-14T01:55:48.609978Z","caller":"traceutil/trace.go:171","msg":"trace[1437728586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"352.39648ms","start":"2026-02-14T01:55:48.257568Z","end":"2026-02-14T01:55:48.609964Z","steps":["trace[1437728586] 'agreement among raft nodes before linearized reading' (duration: 235.473899ms)","trace[1437728586] 'range keys from in-memory index tree' (duration: 116.781898ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:48.609999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:48.257563Z","time spent":"352.430732ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-14T01:55:48.609995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:48.257042Z","time spent":"352.943684ms","remote":"127.0.0.1:36344","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-14T01:55:48.610214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.371584ms","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-14T01:55:48.61023Z","caller":"traceutil/trace.go:171","msg":"trace[238707255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"558.410655ms","start":"2026-02-14T01:55:48.051814Z","end":"2026-02-14T01:55:48.610225Z","steps":["trace[238707255] 'agreement among raft nodes before linearized reading' (duration: 441.190777ms)","trace[238707255] 'range keys from in-memory index tree' (duration: 117.178008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:48.610244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:48.051803Z","time spent":"558.437546ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-14T01:55:48.725873Z","caller":"traceutil/trace.go:171","msg":"trace[1965105222] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"111.531131ms","start":"2026-02-14T01:55:48.614324Z","end":"2026-02-14T01:55:48.725855Z","steps":["trace[1965105222] 'process raft request' (duration: 111.421999ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:48.72629Z","caller":"traceutil/trace.go:171","msg":"trace[1601633949] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:921; }","duration":"111.48394ms","start":"2026-02-14T01:55:48.614798Z","end":"2026-02-14T01:55:48.726282Z","steps":["trace[1601633949] 'read index received' (duration: 111.4689ms)","trace[1601633949] 'applied index is now lower than readState.Index' (duration: 14.53µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:48.726339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.527661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:48.726358Z","caller":"traceutil/trace.go:171","msg":"trace[465529686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"111.569752ms","start":"2026-02-14T01:55:48.614782Z","end":"2026-02-14T01:55:48.726352Z","steps":["trace[465529686] 'agreement among raft nodes before linearized reading' (duration: 111.526921ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:48.75366Z","caller":"traceutil/trace.go:171","msg":"trace[1689354841] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"138.697202ms","start":"2026-02-14T01:55:48.614904Z","end":"2026-02-14T01:55:48.753601Z","steps":["trace[1689354841] 'process raft request' (duration: 138.371454ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:55:48.753792Z","caller":"traceutil/trace.go:171","msg":"trace[247678325] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"137.088753ms","start":"2026-02-14T01:55:48.616697Z","end":"2026-02-14T01:55:48.753786Z","steps":["trace[247678325] 'process raft request' (duration: 136.648303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:49.388388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.143024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:55:49.388446Z","caller":"traceutil/trace.go:171","msg":"trace[268913231] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:888; }","duration":"544.239477ms","start":"2026-02-14T01:55:48.844194Z","end":"2026-02-14T01:55:49.388434Z","steps":["trace[268913231] 'count revisions from in-memory index tree' (duration: 544.098484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:49.388452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.692249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-14T01:55:49.388478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:48.84418Z","time spent":"544.290037ms","remote":"127.0.0.1:36356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-14T01:55:49.388499Z","caller":"traceutil/trace.go:171","msg":"trace[1619455176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:888; }","duration":"218.785041ms","start":"2026-02-14T01:55:49.169703Z","end":"2026-02-14T01:55:49.388488Z","steps":["trace[1619455176] 'range keys from in-memory index tree' (duration: 218.525485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:49.388676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.158075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:55:49.388693Z","caller":"traceutil/trace.go:171","msg":"trace[691646623] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:888; }","duration":"185.229578ms","start":"2026-02-14T01:55:49.203456Z","end":"2026-02-14T01:55:49.388686Z","steps":["trace[691646623] 'count revisions from in-memory index tree' (duration: 185.084324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:57.302507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.817219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:55:57.302567Z","caller":"traceutil/trace.go:171","msg":"trace[2049820040] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"438.896643ms","start":"2026-02-14T01:55:56.863661Z","end":"2026-02-14T01:55:57.302557Z","steps":["trace[2049820040] 'process raft request' (duration: 207.979652ms)","trace[2049820040] 'compare' (duration: 230.718927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:57.302597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:56.863649Z","time spent":"438.935604ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:57.373968Z","caller":"traceutil/trace.go:171","msg":"trace[1609184493] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"508.570711ms","start":"2026-02-14T01:55:56.86538Z","end":"2026-02-14T01:55:57.37395Z","steps":["trace[1609184493] 'process raft request' (duration: 508.536621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:57.374077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:56.865374Z","time spent":"508.645414ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:57.374172Z","caller":"traceutil/trace.go:171","msg":"trace[1941516008] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"509.099434ms","start":"2026-02-14T01:55:56.865052Z","end":"2026-02-14T01:55:57.374152Z","steps":["trace[1941516008] 'process raft request' (duration: 508.754186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:55:57.374264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:55:56.865047Z","time spent":"509.166376ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:55:57.646093Z","caller":"traceutil/trace.go:171","msg":"trace[516316040] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"105.747923ms","start":"2026-02-14T01:55:57.540324Z","end":"2026-02-14T01:55:57.646071Z","steps":["trace[516316040] 'read index received' (duration: 48.763738ms)","trace[516316040] 'applied index is now lower than readState.Index' (duration: 56.982755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:55:57.64633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.991539ms","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-14T01:55:57.646366Z","caller":"traceutil/trace.go:171","msg":"trace[357058309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"106.063241ms","start":"2026-02-14T01:55:57.540291Z","end":"2026-02-14T01:55:57.646354Z","steps":["trace[357058309] 'agreement among raft nodes before linearized reading' (duration: 105.910467ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:00.994461Z","caller":"traceutil/trace.go:171","msg":"trace[1673300009] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"124.030871ms","start":"2026-02-14T01:56:00.870415Z","end":"2026-02-14T01:56:00.994446Z","steps":["trace[1673300009] 'process raft request' (duration: 123.937179ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:01.844752Z","caller":"traceutil/trace.go:171","msg":"trace[447263005] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"180.084424ms","start":"2026-02-14T01:56:01.66465Z","end":"2026-02-14T01:56:01.844734Z","steps":["trace[447263005] 'process raft request' (duration: 179.994822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:02.43903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.710678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:56:02.439083Z","caller":"traceutil/trace.go:171","msg":"trace[1529957950] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:922; }","duration":"362.77304ms","start":"2026-02-14T01:56:02.076296Z","end":"2026-02-14T01:56:02.439069Z","steps":["trace[1529957950] 'count revisions from in-memory index tree' (duration: 362.649787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:02.439112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:02.076255Z","time spent":"362.844921ms","remote":"127.0.0.1:36562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-14T01:56:02.710095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.245826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-14T01:56:02.710215Z","caller":"traceutil/trace.go:171","msg":"trace[1888064210] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"192.017799ms","start":"2026-02-14T01:56:02.51818Z","end":"2026-02-14T01:56:02.710197Z","steps":["trace[1888064210] 'process raft request' (duration: 44.594538ms)","trace[1888064210] 'compare' (duration: 147.108383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:56:03.660273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.952616ms","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-14T01:56:03.660335Z","caller":"traceutil/trace.go:171","msg":"trace[325696057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"265.047009ms","start":"2026-02-14T01:56:03.395273Z","end":"2026-02-14T01:56:03.66032Z","steps":["trace[325696057] 'range keys from in-memory index tree' (duration: 264.831153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:03.660803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.804377ms","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-14T01:56:03.660831Z","caller":"traceutil/trace.go:171","msg":"trace[2693893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"260.875789ms","start":"2026-02-14T01:56:03.399949Z","end":"2026-02-14T01:56:03.660825Z","steps":["trace[2693893] 'range keys from in-memory index tree' (duration: 260.706095ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:03.795255Z","caller":"traceutil/trace.go:171","msg":"trace[1881347437] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"130.420585ms","start":"2026-02-14T01:56:03.664761Z","end":"2026-02-14T01:56:03.795182Z","steps":["trace[1881347437] 'process raft request' (duration: 130.23864ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:03.795991Z","caller":"traceutil/trace.go:171","msg":"trace[739101013] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:965; }","duration":"131.015699ms","start":"2026-02-14T01:56:03.664964Z","end":"2026-02-14T01:56:03.795979Z","steps":["trace[739101013] 'read index received' (duration: 131.009299ms)","trace[739101013] 'applied index is now lower than readState.Index' (duration: 3.71µs)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:56:03.796057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.07802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:56:03.796083Z","caller":"traceutil/trace.go:171","msg":"trace[1472534854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"131.127681ms","start":"2026-02-14T01:56:03.664948Z","end":"2026-02-14T01:56:03.796075Z","steps":["trace[1472534854] 'agreement among raft nodes before linearized reading' (duration: 131.07418ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:03.858855Z","caller":"traceutil/trace.go:171","msg":"trace[1485835052] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"193.797392ms","start":"2026-02-14T01:56:03.665041Z","end":"2026-02-14T01:56:03.858838Z","steps":["trace[1485835052] 'process raft request' (duration: 193.386513ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:04.019383Z","caller":"traceutil/trace.go:171","msg":"trace[1110656666] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"156.320875ms","start":"2026-02-14T01:56:03.863038Z","end":"2026-02-14T01:56:04.019359Z","steps":["trace[1110656666] 'process raft request' (duration: 140.541097ms)","trace[1110656666] 'compare' (duration: 15.548833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:56:07.693883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.376712ms","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-14T01:56:07.693945Z","caller":"traceutil/trace.go:171","msg":"trace[1193837236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"327.488355ms","start":"2026-02-14T01:56:07.366443Z","end":"2026-02-14T01:56:07.693932Z","steps":["trace[1193837236] 'range keys from in-memory index tree' (duration: 327.236019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:07.693979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:07.366427Z","time spent":"327.543096ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-14T01:56:07.694082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.656046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:56:07.694149Z","caller":"traceutil/trace.go:171","msg":"trace[1741977011] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:943; }","duration":"215.765768ms","start":"2026-02-14T01:56:07.478365Z","end":"2026-02-14T01:56:07.69413Z","steps":["trace[1741977011] 'count revisions from in-memory index tree' (duration: 215.594784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:08.188788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.575768ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-14T01:56:08.189729Z","caller":"traceutil/trace.go:171","msg":"trace[1404899578] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"491.274989ms","start":"2026-02-14T01:56:07.698441Z","end":"2026-02-14T01:56:08.189716Z","steps":["trace[1404899578] 'process raft request' (duration: 490.459449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:08.189828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:07.698405Z","time spent":"491.360601ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:56:08.191665Z","caller":"traceutil/trace.go:171","msg":"trace[655917514] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:982; }","duration":"370.414123ms","start":"2026-02-14T01:56:07.81926Z","end":"2026-02-14T01:56:08.189674Z","steps":["trace[655917514] 'read index received' (duration: 110.375873ms)","trace[655917514] 'applied index is now lower than readState.Index' (duration: 260.03696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:56:08.202346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.074097ms","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-14T01:56:08.202445Z","caller":"traceutil/trace.go:171","msg":"trace[1179111784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"383.2336ms","start":"2026-02-14T01:56:07.819197Z","end":"2026-02-14T01:56:08.202431Z","steps":["trace[1179111784] 'agreement among raft nodes before linearized reading' (duration: 372.205166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:08.202478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:07.819177Z","time spent":"383.295511ms","remote":"127.0.0.1:36344","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-14T01:56:08.202501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.819047ms","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-14T01:56:08.20255Z","caller":"traceutil/trace.go:171","msg":"trace[1223810436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"328.903749ms","start":"2026-02-14T01:56:07.873629Z","end":"2026-02-14T01:56:08.202533Z","steps":["trace[1223810436] 'agreement among raft nodes before linearized reading' (duration: 318.10251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:08.202593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:07.873609Z","time spent":"328.975081ms","remote":"127.0.0.1:36344","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-14T01:56:08.202342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.837792ms","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-14T01:56:08.202701Z","caller":"traceutil/trace.go:171","msg":"trace[287275999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"171.225462ms","start":"2026-02-14T01:56:08.031468Z","end":"2026-02-14T01:56:08.202694Z","steps":["trace[287275999] 'agreement among raft nodes before linearized reading' (duration: 160.270279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:23.313186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.703075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:56:23.313274Z","caller":"traceutil/trace.go:171","msg":"trace[987632051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"207.834609ms","start":"2026-02-14T01:56:23.105421Z","end":"2026-02-14T01:56:23.313256Z","steps":["trace[987632051] 'range keys from in-memory index tree' (duration: 207.635934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:23.313629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.224131ms","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-14T01:56:23.313659Z","caller":"traceutil/trace.go:171","msg":"trace[1718446915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"142.292812ms","start":"2026-02-14T01:56:23.171357Z","end":"2026-02-14T01:56:23.31365Z","steps":["trace[1718446915] 'range keys from in-memory index tree' (duration: 142.035107ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:23.41856Z","caller":"traceutil/trace.go:171","msg":"trace[192510937] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"102.034704ms","start":"2026-02-14T01:56:23.316505Z","end":"2026-02-14T01:56:23.41854Z","steps":["trace[192510937] 'process raft request' (duration: 101.915101ms)"],"step_count":1} {"level":"info","ts":"2026-02-14T01:56:59.041366Z","caller":"traceutil/trace.go:171","msg":"trace[1214079283] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"537.357304ms","start":"2026-02-14T01:56:58.504001Z","end":"2026-02-14T01:56:59.041358Z","steps":["trace[1214079283] 'process raft request' (duration: 534.021414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:59.043944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:58.50399Z","time spent":"539.718861ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-14T01:56:59.041257Z","caller":"traceutil/trace.go:171","msg":"trace[1207511001] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1244; }","duration":"490.374405ms","start":"2026-02-14T01:56:58.55087Z","end":"2026-02-14T01:56:59.041244Z","steps":["trace[1207511001] 'read index received' (duration: 487.098686ms)","trace[1207511001] 'applied index is now lower than readState.Index' (duration: 3.275099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-14T01:56:59.045537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.514818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:56:59.045651Z","caller":"traceutil/trace.go:171","msg":"trace[393535460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"473.58411ms","start":"2026-02-14T01:56:58.571987Z","end":"2026-02-14T01:56:59.045571Z","steps":["trace[393535460] 'agreement among raft nodes before linearized reading' (duration: 473.527769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:59.045789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:58.571972Z","time spent":"473.731874ms","remote":"127.0.0.1:36072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-14T01:56:59.048744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.867355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-14T01:56:59.048776Z","caller":"traceutil/trace.go:171","msg":"trace[784022363] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1195; }","duration":"497.925186ms","start":"2026-02-14T01:56:58.550843Z","end":"2026-02-14T01:56:59.048768Z","steps":["trace[784022363] 'agreement among raft nodes before linearized reading' (duration: 494.3516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:56:59.050737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-14T01:56:58.550831Z","time spent":"498.93115ms","remote":"127.0.0.1:36356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-14T01:59:23.448865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.367153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:59:23.44902Z","caller":"traceutil/trace.go:171","msg":"trace[1404244056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1812; }","duration":"114.574868ms","start":"2026-02-14T01:59:23.334426Z","end":"2026-02-14T01:59:23.449001Z","steps":["trace[1404244056] 'range keys from in-memory index tree' (duration: 114.296612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:59:29.274291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.620905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-14T01:59:29.274461Z","caller":"traceutil/trace.go:171","msg":"trace[984909373] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1839; }","duration":"132.82478ms","start":"2026-02-14T01:59:29.141614Z","end":"2026-02-14T01:59:29.274439Z","steps":["trace[984909373] 'count revisions from in-memory index tree' (duration: 132.575674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-14T01:59:29.274732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.659396ms","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-14T01:59:29.274811Z","caller":"traceutil/trace.go:171","msg":"trace[1515639941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1839; }","duration":"122.752437ms","start":"2026-02-14T01:59:29.152048Z","end":"2026-02-14T01:59:29.2748Z","steps":["trace[1515639941] 'range keys from in-memory index tree' (duration: 122.585164ms)"],"step_count":1}