{"level":"warn","ts":"2026-02-17T16:06:21.796801Z","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-17T16:06:21.796894Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-17T16:06:21.79696Z","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-17T16:06:21.79698Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-17T16:06:21.797Z","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-17T16:06:21.797687Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-02-17T16:06:21.797761Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-17T16:06:21.803322Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.157759ms"} {"level":"info","ts":"2026-02-17T16:06:21.810705Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-02-17T16:06:21.810771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T16:06:21.810793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-02-17T16:06:21.810803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T16:06:21.810814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-02-17T16:06:21.81086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-02-17T16:06:21.823407Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T16:06:21.828388Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T16:06:21.832753Z","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-17T16:06:21.837064Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T16:06:21.840453Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T16:06:21.840938Z","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-17T16:06:21.840977Z","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-17T16:06:21.840986Z","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-17T16:06:21.842401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-02-17T16:06:21.846972Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-02-17T16:06:21.847641Z","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-17T16:06:21.847892Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T16:06:21.847926Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T16:06:21.84843Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-17T16:06:21.848468Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-02-17T16:06:22.411091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T16:06:22.411131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T16:06:22.411149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-02-17T16:06:22.411185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-02-17T16:06:22.411197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-17T16:06:22.411205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-02-17T16:06:22.411215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-02-17T16:06:22.414351Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T16:06:22.414461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:06:22.414513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:06:22.414851Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:06:22.416171Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T16:06:22.419553Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-02-17T16:06:22.414921Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T16:06:22.419767Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T16:06:22.419865Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:06:22.420068Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:06:22.420145Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:06:24.247746Z","caller":"traceutil/trace.go:171","msg":"trace[1452158636] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:7; }","duration":"103.870085ms","start":"2026-02-17T16:06:24.143854Z","end":"2026-02-17T16:06:24.247724Z","steps":["trace[1452158636] 'read index received' (duration: 85.084758ms)","trace[1452158636] 'applied index is now lower than readState.Index' (duration: 18.784347ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:06:24.248264Z","caller":"traceutil/trace.go:171","msg":"trace[1722578619] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"111.067179ms","start":"2026-02-17T16:06:24.137182Z","end":"2026-02-17T16:06:24.248249Z","steps":["trace[1722578619] 'process raft request' (duration: 110.477893ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:24.248303Z","caller":"traceutil/trace.go:171","msg":"trace[1135410114] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"111.451849ms","start":"2026-02-17T16:06:24.136796Z","end":"2026-02-17T16:06:24.248248Z","steps":["trace[1135410114] 'process raft request' (duration: 110.817422ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:24.248734Z","caller":"traceutil/trace.go:171","msg":"trace[1305458129] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"111.010058ms","start":"2026-02-17T16:06:24.137712Z","end":"2026-02-17T16:06:24.248722Z","steps":["trace[1305458129] 'process raft request' (duration: 109.96933ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:24.249033Z","caller":"traceutil/trace.go:171","msg":"trace[2107138718] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"129.350803ms","start":"2026-02-17T16:06:24.11962Z","end":"2026-02-17T16:06:24.248971Z","steps":["trace[2107138718] 'process raft request' (duration: 109.308861ms)","trace[2107138718] 'compare' (duration: 18.436778ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:06:24.249487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.307169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:24.249727Z","caller":"traceutil/trace.go:171","msg":"trace[2141732894] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"113.559007ms","start":"2026-02-17T16:06:24.136109Z","end":"2026-02-17T16:06:24.249668Z","steps":["trace[2141732894] 'agreement among raft nodes before linearized reading' (duration: 113.254098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:24.250148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.847612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:24.25028Z","caller":"traceutil/trace.go:171","msg":"trace[789003663] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"117.976326ms","start":"2026-02-17T16:06:24.132283Z","end":"2026-02-17T16:06:24.250259Z","steps":["trace[789003663] 'agreement among raft nodes before linearized reading' (duration: 117.835782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:24.25071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.50715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:24.250769Z","caller":"traceutil/trace.go:171","msg":"trace[2091247579] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:0; response_revision:11; }","duration":"118.578202ms","start":"2026-02-17T16:06:24.132182Z","end":"2026-02-17T16:06:24.25076Z","steps":["trace[2091247579] 'agreement among raft nodes before linearized reading' (duration: 118.50839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:24.250956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.972102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:24.25101Z","caller":"traceutil/trace.go:171","msg":"trace[1795158354] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"126.067745ms","start":"2026-02-17T16:06:24.124936Z","end":"2026-02-17T16:06:24.251003Z","steps":["trace[1795158354] 'agreement among raft nodes before linearized reading' (duration: 125.994153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:24.251241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.735538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:24.251332Z","caller":"traceutil/trace.go:171","msg":"trace[1681550883] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"134.836811ms","start":"2026-02-17T16:06:24.116487Z","end":"2026-02-17T16:06:24.251323Z","steps":["trace[1681550883] 'agreement among raft nodes before linearized reading' (duration: 134.736608ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:24.25171Z","caller":"traceutil/trace.go:171","msg":"trace[35412393] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"115.666533ms","start":"2026-02-17T16:06:24.136018Z","end":"2026-02-17T16:06:24.251685Z","steps":["trace[35412393] 'process raft request' (duration: 111.462169ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:24.251954Z","caller":"traceutil/trace.go:171","msg":"trace[2036018142] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"115.763276ms","start":"2026-02-17T16:06:24.136183Z","end":"2026-02-17T16:06:24.251946Z","steps":["trace[2036018142] 'process raft request' (duration: 111.352847ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:24.252154Z","caller":"traceutil/trace.go:171","msg":"trace[1117525656] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"115.888369ms","start":"2026-02-17T16:06:24.136258Z","end":"2026-02-17T16:06:24.252146Z","steps":["trace[1117525656] 'process raft request' (duration: 111.305116ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:24.288222Z","caller":"traceutil/trace.go:171","msg":"trace[605054370] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"135.298933ms","start":"2026-02-17T16:06:24.152906Z","end":"2026-02-17T16:06:24.288205Z","steps":["trace[605054370] 'process raft request' (duration: 135.20378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:24.289117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.077916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:24.289275Z","caller":"traceutil/trace.go:171","msg":"trace[320224832] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"102.266631ms","start":"2026-02-17T16:06:24.186993Z","end":"2026-02-17T16:06:24.28926Z","steps":["trace[320224832] 'agreement among raft nodes before linearized reading' (duration: 102.087267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:24.290502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.27199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:06:24.292029Z","caller":"traceutil/trace.go:171","msg":"trace[1350800531] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"107.903144ms","start":"2026-02-17T16:06:24.184111Z","end":"2026-02-17T16:06:24.292014Z","steps":["trace[1350800531] 'agreement among raft nodes before linearized reading' (duration: 106.339392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:28.276344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.547707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:06:28.276468Z","caller":"traceutil/trace.go:171","msg":"trace[807817049] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"156.446686ms","start":"2026-02-17T16:06:28.120011Z","end":"2026-02-17T16:06:28.276457Z","steps":["trace[807817049] 'process raft request' (duration: 21.494418ms)","trace[807817049] 'compare' (duration: 134.333932ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:06:28.477089Z","caller":"traceutil/trace.go:171","msg":"trace[203863014] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"103.669454ms","start":"2026-02-17T16:06:28.373404Z","end":"2026-02-17T16:06:28.477073Z","steps":["trace[203863014] 'process raft request' (duration: 103.632863ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:28.477728Z","caller":"traceutil/trace.go:171","msg":"trace[738177591] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"105.774462ms","start":"2026-02-17T16:06:28.371943Z","end":"2026-02-17T16:06:28.477717Z","steps":["trace[738177591] 'process raft request' (duration: 12.983035ms)","trace[738177591] 'compare' (duration: 91.643415ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:06:36.694571Z","caller":"traceutil/trace.go:171","msg":"trace[886145800] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"132.228664ms","start":"2026-02-17T16:06:36.562295Z","end":"2026-02-17T16:06:36.694523Z","steps":["trace[886145800] 'process raft request' (duration: 52.940437ms)","trace[886145800] 'compare' (duration: 79.130632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:06:40.950048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.359635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:06:40.950105Z","caller":"traceutil/trace.go:171","msg":"trace[1366769533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"242.459107ms","start":"2026-02-17T16:06:40.707634Z","end":"2026-02-17T16:06:40.950093Z","steps":["trace[1366769533] 'range keys from in-memory index tree' (duration: 242.233191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.950136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.014719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:06:40.950224Z","caller":"traceutil/trace.go:171","msg":"trace[1802302239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"379.169794ms","start":"2026-02-17T16:06:40.571032Z","end":"2026-02-17T16:06:40.950201Z","steps":["trace[1802302239] 'range keys from in-memory index tree' (duration: 378.917566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.95028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.450696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:06:40.950303Z","caller":"traceutil/trace.go:171","msg":"trace[589176290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"291.497297ms","start":"2026-02-17T16:06:40.658801Z","end":"2026-02-17T16:06:40.950299Z","steps":["trace[589176290] 'range keys from in-memory index tree' (duration: 291.410525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.95027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:40.57101Z","time spent":"379.249646ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:06:40.950462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.175737ms","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-17T16:06:40.950481Z","caller":"traceutil/trace.go:171","msg":"trace[1206107462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"423.191187ms","start":"2026-02-17T16:06:40.527281Z","end":"2026-02-17T16:06:40.950472Z","steps":["trace[1206107462] 'range keys from in-memory index tree' (duration: 423.085824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:40.950494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:40.527228Z","time spent":"423.261808ms","remote":"127.0.0.1:49184","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-17T16:06:41.323395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.047623ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T16:06:41.323799Z","caller":"traceutil/trace.go:171","msg":"trace[402238670] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"365.749107ms","start":"2026-02-17T16:06:40.958031Z","end":"2026-02-17T16:06:41.32378Z","steps":["trace[402238670] 'process raft request' (duration: 365.714406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:41.324033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:40.958025Z","time spent":"365.811218ms","remote":"127.0.0.1:49184","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-17T16:06:41.324612Z","caller":"traceutil/trace.go:171","msg":"trace[407022483] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"373.064247ms","start":"2026-02-17T16:06:40.951514Z","end":"2026-02-17T16:06:41.324578Z","steps":["trace[407022483] 'read index received' (duration: 35.078039ms)","trace[407022483] 'applied index is now lower than readState.Index' (duration: 337.984628ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:06:41.324672Z","caller":"traceutil/trace.go:171","msg":"trace[1947743713] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"368.727319ms","start":"2026-02-17T16:06:40.955934Z","end":"2026-02-17T16:06:41.324662Z","steps":["trace[1947743713] 'process raft request' (duration: 367.763322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:41.324732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:40.955927Z","time spent":"368.764689ms","remote":"127.0.0.1:49184","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-17T16:06:41.325007Z","caller":"traceutil/trace.go:171","msg":"trace[1433120601] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"370.421965ms","start":"2026-02-17T16:06:40.954574Z","end":"2026-02-17T16:06:41.324996Z","steps":["trace[1433120601] 'process raft request' (duration: 368.956235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:41.325072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:40.954563Z","time spent":"370.473206ms","remote":"127.0.0.1:49184","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-17T16:06:41.330484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.961828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:06:41.33057Z","caller":"traceutil/trace.go:171","msg":"trace[575401285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:451; }","duration":"379.068421ms","start":"2026-02-17T16:06:40.951493Z","end":"2026-02-17T16:06:41.330561Z","steps":["trace[575401285] 'agreement among raft nodes before linearized reading' (duration: 373.152589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:41.330611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:40.951482Z","time spent":"379.122612ms","remote":"127.0.0.1:48910","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-17T16:06:48.953038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.063696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:06:48.953128Z","caller":"traceutil/trace.go:171","msg":"trace[67452718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"279.20423ms","start":"2026-02-17T16:06:48.673902Z","end":"2026-02-17T16:06:48.953106Z","steps":["trace[67452718] 'range keys from in-memory index tree' (duration: 279.006985ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:54.439098Z","caller":"traceutil/trace.go:171","msg":"trace[294402060] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"150.058041ms","start":"2026-02-17T16:06:54.289024Z","end":"2026-02-17T16:06:54.439082Z","steps":["trace[294402060] 'process raft request' (duration: 149.949888ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:55.589851Z","caller":"traceutil/trace.go:171","msg":"trace[1531242654] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"107.804437ms","start":"2026-02-17T16:06:55.482026Z","end":"2026-02-17T16:06:55.589831Z","steps":["trace[1531242654] 'process raft request' (duration: 107.666443ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:55.590293Z","caller":"traceutil/trace.go:171","msg":"trace[967488277] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"107.029116ms","start":"2026-02-17T16:06:55.483246Z","end":"2026-02-17T16:06:55.590275Z","steps":["trace[967488277] 'process raft request' (duration: 106.950774ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:57.648531Z","caller":"traceutil/trace.go:171","msg":"trace[1877519484] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"163.181081ms","start":"2026-02-17T16:06:57.485324Z","end":"2026-02-17T16:06:57.648505Z","steps":["trace[1877519484] 'process raft request' (duration: 162.718568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:57.987382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.200679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:06:57.987454Z","caller":"traceutil/trace.go:171","msg":"trace[748040155] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"335.734556ms","start":"2026-02-17T16:06:57.651705Z","end":"2026-02-17T16:06:57.98744Z","steps":["trace[748040155] 'process raft request' (duration: 160.418675ms)","trace[748040155] 'compare' (duration: 175.132596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:06:57.987492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:57.651692Z","time spent":"335.782887ms","remote":"127.0.0.1:49184","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-17T16:06:58.413437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.248264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:06:58.413627Z","caller":"traceutil/trace.go:171","msg":"trace[2042019439] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"155.205842ms","start":"2026-02-17T16:06:58.2584Z","end":"2026-02-17T16:06:58.413606Z","steps":["trace[2042019439] 'process raft request' (duration: 155.14029ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:06:58.413744Z","caller":"traceutil/trace.go:171","msg":"trace[79864877] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"760.491646ms","start":"2026-02-17T16:06:57.653231Z","end":"2026-02-17T16:06:58.413723Z","steps":["trace[79864877] 'process raft request' (duration: 593.871092ms)","trace[79864877] 'compare' (duration: 166.151141ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:06:58.41384Z","caller":"traceutil/trace.go:171","msg":"trace[77308548] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:539; }","duration":"754.898494ms","start":"2026-02-17T16:06:57.65893Z","end":"2026-02-17T16:06:58.413829Z","steps":["trace[77308548] 'read index received' (duration: 153.203038ms)","trace[77308548] 'applied index is now lower than readState.Index' (duration: 601.694256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:06:58.413863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:57.65322Z","time spent":"760.570049ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:06:58.413903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.964426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:06:58.413932Z","caller":"traceutil/trace.go:171","msg":"trace[814049447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"755.011186ms","start":"2026-02-17T16:06:57.658911Z","end":"2026-02-17T16:06:58.413922Z","steps":["trace[814049447] 'agreement among raft nodes before linearized reading' (duration: 754.958105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:06:58.413961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:06:57.658903Z","time spent":"755.051388ms","remote":"127.0.0.1:48906","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-17T16:07:03.373561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763478094215043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:07:03.874062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763478094215043,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:07:03.916345Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.047781082s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:07:03.916711Z","caller":"traceutil/trace.go:171","msg":"trace[677624929] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.048180272s","start":"2026-02-17T16:07:02.868512Z","end":"2026-02-17T16:07:03.916693Z","steps":["trace[677624929] 'process raft request' (duration: 1.048046029s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:03.916813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:02.868485Z","time spent":"1.048261525s","remote":"127.0.0.1:49184","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-17T16:07:03.917326Z","caller":"traceutil/trace.go:171","msg":"trace[927973837] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"1.043808533s","start":"2026-02-17T16:07:02.873508Z","end":"2026-02-17T16:07:03.917317Z","steps":["trace[927973837] 'read index received' (duration: 1.043444673s)","trace[927973837] 'applied index is now lower than readState.Index' (duration: 363.06µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:03.917428Z","caller":"traceutil/trace.go:171","msg":"trace[488349309] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.048786859s","start":"2026-02-17T16:07:02.868633Z","end":"2026-02-17T16:07:03.91742Z","steps":["trace[488349309] 'process raft request' (duration: 1.048610774s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:03.91748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:02.868629Z","time spent":"1.04881939s","remote":"127.0.0.1:49184","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-17T16:07:03.917619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044106121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:07:03.917655Z","caller":"traceutil/trace.go:171","msg":"trace[169441213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.044162072s","start":"2026-02-17T16:07:02.873484Z","end":"2026-02-17T16:07:03.917647Z","steps":["trace[169441213] 'agreement among raft nodes before linearized reading' (duration: 1.044053589s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:03.917682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:02.873473Z","time spent":"1.044202214s","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:07:03.917807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035487506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:07:03.91783Z","caller":"traceutil/trace.go:171","msg":"trace[876737717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.035541927s","start":"2026-02-17T16:07:02.882282Z","end":"2026-02-17T16:07:03.917824Z","steps":["trace[876737717] 'agreement among raft nodes before linearized reading' (duration: 1.035471595s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:03.917861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:02.882268Z","time spent":"1.035586209s","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:07:03.917939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020514957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:07:03.917961Z","caller":"traceutil/trace.go:171","msg":"trace[1275558694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"1.020556709s","start":"2026-02-17T16:07:02.897398Z","end":"2026-02-17T16:07:03.917955Z","steps":["trace[1275558694] 'agreement among raft nodes before linearized reading' (duration: 1.020516658s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:03.918063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:02.897385Z","time spent":"1.02060151s","remote":"127.0.0.1:48906","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-17T16:07:06.162823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763478094215056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:07:06.66332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763478094215056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:07:07.14137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.90679857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T16:07:07.141442Z","caller":"traceutil/trace.go:171","msg":"trace[1501597824] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:537; }","duration":"2.906906854s","start":"2026-02-17T16:07:04.234519Z","end":"2026-02-17T16:07:07.141426Z","steps":["trace[1501597824] 'range keys from in-memory index tree' (duration: 2.906725329s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.141517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:04.234504Z","time spent":"2.906970295s","remote":"127.0.0.1:49030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-17T16:07:07.142742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:04.851675Z","time spent":"2.291064502s","remote":"127.0.0.1:56006","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T16:07:07.143045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.234744075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cz5vm\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T16:07:07.143084Z","caller":"traceutil/trace.go:171","msg":"trace[1671577826] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cz5vm; range_end:; response_count:1; response_revision:537; }","duration":"2.234808927s","start":"2026-02-17T16:07:04.908262Z","end":"2026-02-17T16:07:07.143071Z","steps":["trace[1671577826] 'range keys from in-memory index tree' (duration: 2.234657242s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.143118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:04.908247Z","time spent":"2.234863148s","remote":"127.0.0.1:49110","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-cz5vm\" "} {"level":"warn","ts":"2026-02-17T16:07:07.164551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763478094215056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:07:07.204349Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.965892007s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:07:07.20474Z","caller":"traceutil/trace.go:171","msg":"trace[1685663305] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.966373561s","start":"2026-02-17T16:07:05.238346Z","end":"2026-02-17T16:07:07.204719Z","steps":["trace[1685663305] 'process raft request' (duration: 1.966238947s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.204856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:05.23832Z","time spent":"1.966465934s","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:07:07.334333Z","caller":"traceutil/trace.go:171","msg":"trace[1091000786] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"1.672310578s","start":"2026-02-17T16:07:05.662001Z","end":"2026-02-17T16:07:07.334312Z","steps":["trace[1091000786] 'read index received' (duration: 1.54324136s)","trace[1091000786] 'applied index is now lower than readState.Index' (duration: 129.068318ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:07:07.334795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67276415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:07:07.33494Z","caller":"traceutil/trace.go:171","msg":"trace[1720955557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"1.672971565s","start":"2026-02-17T16:07:05.661946Z","end":"2026-02-17T16:07:07.334918Z","steps":["trace[1720955557] 'agreement among raft nodes before linearized reading' (duration: 1.672744969s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.335071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:05.661923Z","time spent":"1.673069168s","remote":"127.0.0.1:48906","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-17T16:07:07.335292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526459256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:07:07.335425Z","caller":"traceutil/trace.go:171","msg":"trace[1622254486] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:539; }","duration":"1.526666882s","start":"2026-02-17T16:07:05.808742Z","end":"2026-02-17T16:07:07.335409Z","steps":["trace[1622254486] 'agreement among raft nodes before linearized reading' (duration: 1.526301742s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.336099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:05.808719Z","time spent":"1.527363891s","remote":"127.0.0.1:48952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-02-17T16:07:07.336623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.180059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:07:07.336719Z","caller":"traceutil/trace.go:171","msg":"trace[1558741903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"186.285811ms","start":"2026-02-17T16:07:07.150421Z","end":"2026-02-17T16:07:07.336707Z","steps":["trace[1558741903] 'agreement among raft nodes before linearized reading' (duration: 186.169898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.336977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.61729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-17T16:07:07.337099Z","caller":"traceutil/trace.go:171","msg":"trace[860220805] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:539; }","duration":"186.779335ms","start":"2026-02-17T16:07:07.150309Z","end":"2026-02-17T16:07:07.337088Z","steps":["trace[860220805] 'agreement among raft nodes before linearized reading' (duration: 186.541668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.337454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388047588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:07:07.337546Z","caller":"traceutil/trace.go:171","msg":"trace[9703040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.388160221s","start":"2026-02-17T16:07:05.949375Z","end":"2026-02-17T16:07:07.337535Z","steps":["trace[9703040] 'agreement among raft nodes before linearized reading' (duration: 1.387980046s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.337636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:05.949366Z","time spent":"1.388259153s","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:07:07.338252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389576539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:07:07.33833Z","caller":"traceutil/trace.go:171","msg":"trace[788219688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.389707903s","start":"2026-02-17T16:07:05.948611Z","end":"2026-02-17T16:07:07.338319Z","steps":["trace[788219688] 'agreement among raft nodes before linearized reading' (duration: 1.389535618s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.338424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:05.948587Z","time spent":"1.389825067s","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:07:07.338956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416269388s","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-17T16:07:07.33928Z","caller":"traceutil/trace.go:171","msg":"trace[448032089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"1.416606387s","start":"2026-02-17T16:07:05.92266Z","end":"2026-02-17T16:07:07.339266Z","steps":["trace[448032089] 'agreement among raft nodes before linearized reading' (duration: 1.416128434s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.340368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:05.922653Z","time spent":"1.417704698s","remote":"127.0.0.1:49184","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-17T16:07:07.340505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418045477s","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-17T16:07:07.345622Z","caller":"traceutil/trace.go:171","msg":"trace[258966576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"1.423186618s","start":"2026-02-17T16:07:05.922424Z","end":"2026-02-17T16:07:07.345611Z","steps":["trace[258966576] 'agreement among raft nodes before linearized reading' (duration: 1.418043207s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:07.345671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:05.922408Z","time spent":"1.423250159s","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T16:07:07.335352Z","caller":"traceutil/trace.go:171","msg":"trace[372387635] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"180.049302ms","start":"2026-02-17T16:07:07.155289Z","end":"2026-02-17T16:07:07.335338Z","steps":["trace[372387635] 'process raft request' (duration: 178.574962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:14.969254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.541928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:07:14.969325Z","caller":"traceutil/trace.go:171","msg":"trace[118106392] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"375.131584ms","start":"2026-02-17T16:07:14.594183Z","end":"2026-02-17T16:07:14.969314Z","steps":["trace[118106392] 'read index received' (duration: 33.271µs)","trace[118106392] 'applied index is now lower than readState.Index' (duration: 375.097833ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:14.969443Z","caller":"traceutil/trace.go:171","msg":"trace[817894839] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"495.836092ms","start":"2026-02-17T16:07:14.4736Z","end":"2026-02-17T16:07:14.969437Z","steps":["trace[817894839] 'process raft request' (duration: 84.060977ms)","trace[817894839] 'compare' (duration: 411.210509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:07:14.969474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:14.473583Z","time spent":"495.873733ms","remote":"127.0.0.1:48968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:14.969604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.422711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-17T16:07:14.969621Z","caller":"traceutil/trace.go:171","msg":"trace[1279802538] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:619; }","duration":"375.470453ms","start":"2026-02-17T16:07:14.594143Z","end":"2026-02-17T16:07:14.969614Z","steps":["trace[1279802538] 'agreement among raft nodes before linearized reading' (duration: 375.411191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:14.969638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:14.594123Z","time spent":"375.512414ms","remote":"127.0.0.1:48968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-17T16:07:15.041065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.200239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:07:15.041126Z","caller":"traceutil/trace.go:171","msg":"trace[569889429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"381.286822ms","start":"2026-02-17T16:07:14.65982Z","end":"2026-02-17T16:07:15.041107Z","steps":["trace[569889429] 'agreement among raft nodes before linearized reading' (duration: 380.827099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.041149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:14.659805Z","time spent":"381.338773ms","remote":"127.0.0.1:48906","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-17T16:07:15.040662Z","caller":"traceutil/trace.go:171","msg":"trace[491069201] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"213.694701ms","start":"2026-02-17T16:07:14.826946Z","end":"2026-02-17T16:07:15.040641Z","steps":["trace[491069201] 'process raft request' (duration: 213.580948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.686969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.346485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:07:15.687085Z","caller":"traceutil/trace.go:171","msg":"trace[1620815864] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"622.449814ms","start":"2026-02-17T16:07:15.064618Z","end":"2026-02-17T16:07:15.687067Z","steps":["trace[1620815864] 'process raft request' (duration: 158.914504ms)","trace[1620815864] 'compare' (duration: 463.128879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:07:15.687163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.064605Z","time spent":"622.530536ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:07:15.704287Z","caller":"traceutil/trace.go:171","msg":"trace[676614786] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"465.943565ms","start":"2026-02-17T16:07:15.238329Z","end":"2026-02-17T16:07:15.704273Z","steps":["trace[676614786] 'read index received' (duration: 465.578385ms)","trace[676614786] 'applied index is now lower than readState.Index' (duration: 364.6µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:15.704675Z","caller":"traceutil/trace.go:171","msg":"trace[2073941892] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"551.262538ms","start":"2026-02-17T16:07:15.153404Z","end":"2026-02-17T16:07:15.704666Z","steps":["trace[2073941892] 'process raft request' (duration: 550.60896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.704738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.153387Z","time spent":"551.307879ms","remote":"127.0.0.1:48968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:15.704861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.529991ms","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-17T16:07:15.704876Z","caller":"traceutil/trace.go:171","msg":"trace[1946160249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:622; }","duration":"466.570502ms","start":"2026-02-17T16:07:15.238301Z","end":"2026-02-17T16:07:15.704872Z","steps":["trace[1946160249] 'agreement among raft nodes before linearized reading' (duration: 466.508641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.70489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.238288Z","time spent":"466.599373ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T16:07:15.800927Z","caller":"traceutil/trace.go:171","msg":"trace[177382871] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"551.889845ms","start":"2026-02-17T16:07:15.249022Z","end":"2026-02-17T16:07:15.800912Z","steps":["trace[177382871] 'process raft request' (duration: 544.351968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.800996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.249006Z","time spent":"551.953597ms","remote":"127.0.0.1:48968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:07:15.801142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.048716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-17T16:07:15.80116Z","caller":"traceutil/trace.go:171","msg":"trace[1280373087] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:624; }","duration":"407.101997ms","start":"2026-02-17T16:07:15.394052Z","end":"2026-02-17T16:07:15.801154Z","steps":["trace[1280373087] 'agreement among raft nodes before linearized reading' (duration: 407.006475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.801195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.394032Z","time spent":"407.159278ms","remote":"127.0.0.1:48968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-17T16:07:15.801571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.177962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-02-17T16:07:15.801587Z","caller":"traceutil/trace.go:171","msg":"trace[960657178] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:624; }","duration":"207.216154ms","start":"2026-02-17T16:07:15.594366Z","end":"2026-02-17T16:07:15.801582Z","steps":["trace[960657178] 'agreement among raft nodes before linearized reading' (duration: 207.098501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.801727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.699537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:07:15.80174Z","caller":"traceutil/trace.go:171","msg":"trace[1197429773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"327.718468ms","start":"2026-02-17T16:07:15.474017Z","end":"2026-02-17T16:07:15.801736Z","steps":["trace[1197429773] 'agreement among raft nodes before linearized reading' (duration: 327.672097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.801752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.474015Z","time spent":"327.734278ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:07:15.801825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.81483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:07:15.801837Z","caller":"traceutil/trace.go:171","msg":"trace[912633845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"327.858552ms","start":"2026-02-17T16:07:15.473975Z","end":"2026-02-17T16:07:15.801833Z","steps":["trace[912633845] 'agreement among raft nodes before linearized reading' (duration: 327.824911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.801847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.47397Z","time spent":"327.874932ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:07:15.801915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.939611ms","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-17T16:07:15.801926Z","caller":"traceutil/trace.go:171","msg":"trace[1475445310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"328.970002ms","start":"2026-02-17T16:07:15.472952Z","end":"2026-02-17T16:07:15.801922Z","steps":["trace[1475445310] 'agreement among raft nodes before linearized reading' (duration: 328.937321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.801937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.472945Z","time spent":"328.989572ms","remote":"127.0.0.1:49184","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-17T16:07:15.80278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.504263ms","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-17T16:07:15.802797Z","caller":"traceutil/trace.go:171","msg":"trace[405802583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"345.553995ms","start":"2026-02-17T16:07:15.457238Z","end":"2026-02-17T16:07:15.802792Z","steps":["trace[405802583] 'agreement among raft nodes before linearized reading' (duration: 345.505044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:15.802811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:15.457212Z","time spent":"345.595676ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T16:07:28.290251Z","caller":"traceutil/trace.go:171","msg":"trace[1422357647] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"402.303065ms","start":"2026-02-17T16:07:27.887934Z","end":"2026-02-17T16:07:28.290237Z","steps":["trace[1422357647] 'read index received' (duration: 402.196152ms)","trace[1422357647] 'applied index is now lower than readState.Index' (duration: 106.423µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:28.290403Z","caller":"traceutil/trace.go:171","msg":"trace[592969080] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"427.566625ms","start":"2026-02-17T16:07:27.862831Z","end":"2026-02-17T16:07:28.290398Z","steps":["trace[592969080] 'process raft request' (duration: 427.334369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:28.290487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:27.862816Z","time spent":"427.615477ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:28.290633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.697566ms","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-17T16:07:28.290653Z","caller":"traceutil/trace.go:171","msg":"trace[1255760814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"402.739927ms","start":"2026-02-17T16:07:27.887909Z","end":"2026-02-17T16:07:28.290649Z","steps":["trace[1255760814] 'agreement among raft nodes before linearized reading' (duration: 402.671605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:28.290667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:27.8879Z","time spent":"402.764968ms","remote":"127.0.0.1:49184","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-17T16:07:28.290763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.171931ms","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-17T16:07:28.290781Z","caller":"traceutil/trace.go:171","msg":"trace[797943612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:727; }","duration":"402.199062ms","start":"2026-02-17T16:07:27.888577Z","end":"2026-02-17T16:07:28.290776Z","steps":["trace[797943612] 'agreement among raft nodes before linearized reading' (duration: 402.158751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:28.290794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:27.888571Z","time spent":"402.219963ms","remote":"127.0.0.1:49184","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-17T16:07:28.290907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.732851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:07:28.290924Z","caller":"traceutil/trace.go:171","msg":"trace[473284762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"359.760512ms","start":"2026-02-17T16:07:27.93116Z","end":"2026-02-17T16:07:28.290921Z","steps":["trace[473284762] 'agreement among raft nodes before linearized reading' (duration: 359.719681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:28.290937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:27.931156Z","time spent":"359.779022ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:07:28.291026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.876066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:07:28.291042Z","caller":"traceutil/trace.go:171","msg":"trace[170607054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"366.911607ms","start":"2026-02-17T16:07:27.924127Z","end":"2026-02-17T16:07:28.291039Z","steps":["trace[170607054] 'agreement among raft nodes before linearized reading' (duration: 366.862046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:28.291055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:27.924122Z","time spent":"366.929687ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:07:28.29113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.937632ms","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-17T16:07:28.291147Z","caller":"traceutil/trace.go:171","msg":"trace[695765678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"387.968653ms","start":"2026-02-17T16:07:27.903175Z","end":"2026-02-17T16:07:28.291143Z","steps":["trace[695765678] 'agreement among raft nodes before linearized reading' (duration: 387.931402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:28.291159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:27.903166Z","time spent":"387.989974ms","remote":"127.0.0.1:49184","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-17T16:07:31.312091Z","caller":"traceutil/trace.go:171","msg":"trace[1036567740] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"931.898601ms","start":"2026-02-17T16:07:30.380171Z","end":"2026-02-17T16:07:31.312069Z","steps":["trace[1036567740] 'process raft request' (duration: 931.780908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:31.312231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:30.380159Z","time spent":"931.998783ms","remote":"127.0.0.1:49184","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-17T16:07:31.522941Z","caller":"traceutil/trace.go:171","msg":"trace[1830785343] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.140806681s","start":"2026-02-17T16:07:30.38211Z","end":"2026-02-17T16:07:31.522917Z","steps":["trace[1830785343] 'process raft request' (duration: 1.140536064s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:31.523084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:30.382102Z","time spent":"1.140915004s","remote":"127.0.0.1:49184","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-17T16:07:31.523443Z","caller":"traceutil/trace.go:171","msg":"trace[358997171] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"1.142079546s","start":"2026-02-17T16:07:30.381352Z","end":"2026-02-17T16:07:31.523432Z","steps":["trace[358997171] 'process raft request' (duration: 1.141184202s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:31.523497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:30.381344Z","time spent":"1.142124237s","remote":"127.0.0.1:49184","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-17T16:07:31.523829Z","caller":"traceutil/trace.go:171","msg":"trace[59881045] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.141633654s","start":"2026-02-17T16:07:30.382185Z","end":"2026-02-17T16:07:31.523819Z","steps":["trace[59881045] 'process raft request' (duration: 1.140535114s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:31.523865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:30.382181Z","time spent":"1.141664975s","remote":"127.0.0.1:49184","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-17T16:07:31.523986Z","caller":"traceutil/trace.go:171","msg":"trace[1281523780] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"1.140320598s","start":"2026-02-17T16:07:30.383653Z","end":"2026-02-17T16:07:31.523974Z","steps":["trace[1281523780] 'process raft request' (duration: 1.139130186s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:31.524014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:30.383648Z","time spent":"1.140350469s","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:07:31.677579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.75023ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T16:07:43.715412Z","caller":"traceutil/trace.go:171","msg":"trace[2023256806] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"123.359251ms","start":"2026-02-17T16:07:43.59203Z","end":"2026-02-17T16:07:43.71539Z","steps":["trace[2023256806] 'read index received' (duration: 123.181916ms)","trace[2023256806] 'applied index is now lower than readState.Index' (duration: 176.455µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:07:43.715788Z","caller":"traceutil/trace.go:171","msg":"trace[1395104255] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"150.331608ms","start":"2026-02-17T16:07:43.565443Z","end":"2026-02-17T16:07:43.715774Z","steps":["trace[1395104255] 'process raft request' (duration: 149.828924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:43.716114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.097353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:07:43.716259Z","caller":"traceutil/trace.go:171","msg":"trace[2075906473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"110.34736ms","start":"2026-02-17T16:07:43.605887Z","end":"2026-02-17T16:07:43.716235Z","steps":["trace[2075906473] 'agreement among raft nodes before linearized reading' (duration: 109.98432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:43.716291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.250945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:07:43.716331Z","caller":"traceutil/trace.go:171","msg":"trace[1524123222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:774; }","duration":"124.352548ms","start":"2026-02-17T16:07:43.591967Z","end":"2026-02-17T16:07:43.71632Z","steps":["trace[1524123222] 'agreement among raft nodes before linearized reading' (duration: 124.229915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:43.716535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.98449ms","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-17T16:07:43.716589Z","caller":"traceutil/trace.go:171","msg":"trace[1186800388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:774; }","duration":"101.055853ms","start":"2026-02-17T16:07:43.615522Z","end":"2026-02-17T16:07:43.716578Z","steps":["trace[1186800388] 'agreement among raft nodes before linearized reading' (duration: 100.914349ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:46.024661Z","caller":"traceutil/trace.go:171","msg":"trace[1389726339] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"362.120191ms","start":"2026-02-17T16:07:45.662517Z","end":"2026-02-17T16:07:46.024638Z","steps":["trace[1389726339] 'read index received' (duration: 361.862884ms)","trace[1389726339] 'applied index is now lower than readState.Index' (duration: 256.467µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:07:46.02513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.561254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:07:46.025524Z","caller":"traceutil/trace.go:171","msg":"trace[813740557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"363.013846ms","start":"2026-02-17T16:07:45.662475Z","end":"2026-02-17T16:07:46.025489Z","steps":["trace[813740557] 'agreement among raft nodes before linearized reading' (duration: 362.348048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:46.025785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:45.662455Z","time spent":"363.18784ms","remote":"127.0.0.1:48906","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-17T16:07:46.027367Z","caller":"traceutil/trace.go:171","msg":"trace[967106588] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"664.020053ms","start":"2026-02-17T16:07:45.363331Z","end":"2026-02-17T16:07:46.027351Z","steps":["trace[967106588] 'process raft request' (duration: 661.177794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:46.027529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:45.363294Z","time spent":"664.148046ms","remote":"127.0.0.1:49184","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-17T16:07:46.03189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.906849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:07:46.031936Z","caller":"traceutil/trace.go:171","msg":"trace[851668308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"215.006932ms","start":"2026-02-17T16:07:45.816922Z","end":"2026-02-17T16:07:46.031928Z","steps":["trace[851668308] 'agreement among raft nodes before linearized reading' (duration: 214.879718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:46.032204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.319783ms","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-17T16:07:46.032231Z","caller":"traceutil/trace.go:171","msg":"trace[949850919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"214.386445ms","start":"2026-02-17T16:07:45.817838Z","end":"2026-02-17T16:07:46.032225Z","steps":["trace[949850919] 'agreement among raft nodes before linearized reading' (duration: 214.306213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:46.032842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.107878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:07:46.032887Z","caller":"traceutil/trace.go:171","msg":"trace[1710223521] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:778; }","duration":"218.508149ms","start":"2026-02-17T16:07:45.814372Z","end":"2026-02-17T16:07:46.03288Z","steps":["trace[1710223521] 'agreement among raft nodes before linearized reading' (duration: 218.400376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:46.033144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.660778ms","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-17T16:07:46.033293Z","caller":"traceutil/trace.go:171","msg":"trace[71025791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"312.157836ms","start":"2026-02-17T16:07:45.721099Z","end":"2026-02-17T16:07:46.033256Z","steps":["trace[71025791] 'agreement among raft nodes before linearized reading' (duration: 304.082832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:46.033891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:07:45.721026Z","time spent":"312.826084ms","remote":"127.0.0.1:49184","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-17T16:07:46.033526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.567118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:07:46.03431Z","caller":"traceutil/trace.go:171","msg":"trace[2008579073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"273.365771ms","start":"2026-02-17T16:07:45.760932Z","end":"2026-02-17T16:07:46.034298Z","steps":["trace[2008579073] 'agreement among raft nodes before linearized reading' (duration: 272.520747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:46.258098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.169259ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-17T16:07:46.258701Z","caller":"traceutil/trace.go:171","msg":"trace[583692545] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"220.273647ms","start":"2026-02-17T16:07:46.038402Z","end":"2026-02-17T16:07:46.258675Z","steps":["trace[583692545] 'process raft request' (duration: 219.827165ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:46.259053Z","caller":"traceutil/trace.go:171","msg":"trace[1440548452] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"218.818687ms","start":"2026-02-17T16:07:46.040215Z","end":"2026-02-17T16:07:46.259034Z","steps":["trace[1440548452] 'process raft request' (duration: 218.19628ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:46.259232Z","caller":"traceutil/trace.go:171","msg":"trace[2038730704] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"218.96516ms","start":"2026-02-17T16:07:46.040252Z","end":"2026-02-17T16:07:46.259217Z","steps":["trace[2038730704] 'process raft request' (duration: 218.274891ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:46.259346Z","caller":"traceutil/trace.go:171","msg":"trace[683438845] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"218.263421ms","start":"2026-02-17T16:07:46.04107Z","end":"2026-02-17T16:07:46.259333Z","steps":["trace[683438845] 'process raft request' (duration: 217.544061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:07:46.582701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.251089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-17T16:07:46.582757Z","caller":"traceutil/trace.go:171","msg":"trace[1699552110] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:783; }","duration":"224.33281ms","start":"2026-02-17T16:07:46.358411Z","end":"2026-02-17T16:07:46.582744Z","steps":["trace[1699552110] 'range keys from in-memory index tree' (duration: 224.187397ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:57.378543Z","caller":"traceutil/trace.go:171","msg":"trace[1653680143] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"104.120467ms","start":"2026-02-17T16:07:57.274404Z","end":"2026-02-17T16:07:57.378525Z","steps":["trace[1653680143] 'process raft request' (duration: 104.090196ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:57.378716Z","caller":"traceutil/trace.go:171","msg":"trace[1183192035] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"114.31946ms","start":"2026-02-17T16:07:57.264389Z","end":"2026-02-17T16:07:57.378709Z","steps":["trace[1183192035] 'process raft request' (duration: 113.858428ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:57.378774Z","caller":"traceutil/trace.go:171","msg":"trace[505856705] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"104.509558ms","start":"2026-02-17T16:07:57.274258Z","end":"2026-02-17T16:07:57.378768Z","steps":["trace[505856705] 'process raft request' (duration: 104.089996ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:57.378973Z","caller":"traceutil/trace.go:171","msg":"trace[85715408] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"104.684152ms","start":"2026-02-17T16:07:57.27428Z","end":"2026-02-17T16:07:57.378965Z","steps":["trace[85715408] 'process raft request' (duration: 104.107117ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:57.379033Z","caller":"traceutil/trace.go:171","msg":"trace[828857876] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"104.654172ms","start":"2026-02-17T16:07:57.274369Z","end":"2026-02-17T16:07:57.379023Z","steps":["trace[828857876] 'process raft request' (duration: 104.104607ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:57.379253Z","caller":"traceutil/trace.go:171","msg":"trace[1361655124] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"104.919549ms","start":"2026-02-17T16:07:57.274325Z","end":"2026-02-17T16:07:57.379244Z","steps":["trace[1361655124] 'process raft request' (duration: 104.101797ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:57.510535Z","caller":"traceutil/trace.go:171","msg":"trace[310806553] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"106.688028ms","start":"2026-02-17T16:07:57.403822Z","end":"2026-02-17T16:07:57.51051Z","steps":["trace[310806553] 'process raft request' (duration: 106.087381ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:07:57.510733Z","caller":"traceutil/trace.go:171","msg":"trace[1832017010] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"106.844753ms","start":"2026-02-17T16:07:57.403879Z","end":"2026-02-17T16:07:57.510723Z","steps":["trace[1832017010] 'process raft request' (duration: 106.194895ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:02.705003Z","caller":"traceutil/trace.go:171","msg":"trace[1052149291] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"415.009698ms","start":"2026-02-17T16:08:02.289973Z","end":"2026-02-17T16:08:02.704983Z","steps":["trace[1052149291] 'read index received' (duration: 414.778902ms)","trace[1052149291] 'applied index is now lower than readState.Index' (duration: 229.916µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:08:02.705316Z","caller":"traceutil/trace.go:171","msg":"trace[125220469] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"587.534042ms","start":"2026-02-17T16:08:02.117771Z","end":"2026-02-17T16:08:02.705305Z","steps":["trace[125220469] 'process raft request' (duration: 587.046058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:02.705517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:02.117762Z","time spent":"587.586773ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:08:02.705757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.373604ms","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-17T16:08:02.70605Z","caller":"traceutil/trace.go:171","msg":"trace[76324518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"311.689723ms","start":"2026-02-17T16:08:02.39435Z","end":"2026-02-17T16:08:02.706039Z","steps":["trace[76324518] 'agreement among raft nodes before linearized reading' (duration: 311.20787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:02.706074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.069637ms","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":"warn","ts":"2026-02-17T16:08:02.706117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:02.39433Z","time spent":"311.777565ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T16:08:02.706136Z","caller":"traceutil/trace.go:171","msg":"trace[2025558451] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:880; }","duration":"416.184951ms","start":"2026-02-17T16:08:02.289936Z","end":"2026-02-17T16:08:02.706121Z","steps":["trace[2025558451] 'agreement among raft nodes before linearized reading' (duration: 415.985415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:02.706168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:02.289918Z","time spent":"416.243823ms","remote":"127.0.0.1:49198","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-17T16:08:02.706442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.015824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:08:02.706457Z","caller":"traceutil/trace.go:171","msg":"trace[763433580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"236.065766ms","start":"2026-02-17T16:08:02.470386Z","end":"2026-02-17T16:08:02.706451Z","steps":["trace[763433580] 'agreement among raft nodes before linearized reading' (duration: 235.994494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:02.706511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.807731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:08:02.70652Z","caller":"traceutil/trace.go:171","msg":"trace[1281970128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"247.854363ms","start":"2026-02-17T16:08:02.458663Z","end":"2026-02-17T16:08:02.706517Z","steps":["trace[1281970128] 'agreement among raft nodes before linearized reading' (duration: 247.822982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:02.706571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.036857ms","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-17T16:08:02.706582Z","caller":"traceutil/trace.go:171","msg":"trace[1518450551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"257.073258ms","start":"2026-02-17T16:08:02.449505Z","end":"2026-02-17T16:08:02.706579Z","steps":["trace[1518450551] 'agreement among raft nodes before linearized reading' (duration: 257.041357ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:02.839074Z","caller":"traceutil/trace.go:171","msg":"trace[2059740786] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"117.343214ms","start":"2026-02-17T16:08:02.721714Z","end":"2026-02-17T16:08:02.839057Z","steps":["trace[2059740786] 'process raft request' (duration: 117.322353ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:02.839167Z","caller":"traceutil/trace.go:171","msg":"trace[1684658712] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"120.310386ms","start":"2026-02-17T16:08:02.718791Z","end":"2026-02-17T16:08:02.839101Z","steps":["trace[1684658712] 'process raft request' (duration: 120.132211ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:02.839299Z","caller":"traceutil/trace.go:171","msg":"trace[505162445] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"118.264979ms","start":"2026-02-17T16:08:02.721029Z","end":"2026-02-17T16:08:02.839294Z","steps":["trace[505162445] 'process raft request' (duration: 117.987912ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:09.981218Z","caller":"traceutil/trace.go:171","msg":"trace[1914720512] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"321.807394ms","start":"2026-02-17T16:08:09.659395Z","end":"2026-02-17T16:08:09.981202Z","steps":["trace[1914720512] 'read index received' (duration: 321.68377ms)","trace[1914720512] 'applied index is now lower than readState.Index' (duration: 122.994µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:09.981324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.912566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:08:09.98135Z","caller":"traceutil/trace.go:171","msg":"trace[1086188966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"321.969648ms","start":"2026-02-17T16:08:09.659374Z","end":"2026-02-17T16:08:09.981343Z","steps":["trace[1086188966] 'agreement among raft nodes before linearized reading' (duration: 321.899086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:09.981374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:09.659367Z","time spent":"322.001519ms","remote":"127.0.0.1:48906","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-17T16:08:09.981578Z","caller":"traceutil/trace.go:171","msg":"trace[400701961] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"325.437464ms","start":"2026-02-17T16:08:09.656135Z","end":"2026-02-17T16:08:09.981572Z","steps":["trace[400701961] 'process raft request' (duration: 324.982251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:09.981633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:09.656119Z","time spent":"325.478826ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:08:13.495225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763478094215868,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T16:08:13.676042Z","caller":"traceutil/trace.go:171","msg":"trace[99171916] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"762.636197ms","start":"2026-02-17T16:08:12.913378Z","end":"2026-02-17T16:08:13.676014Z","steps":["trace[99171916] 'process raft request' (duration: 729.763715ms)","trace[99171916] 'compare' (duration: 32.571973ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:13.676135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:12.913364Z","time spent":"762.7194ms","remote":"127.0.0.1:49184","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-17T16:08:14.435125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.433195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:08:14.435212Z","caller":"traceutil/trace.go:171","msg":"trace[1153583257] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:944; }","duration":"1.440151354s","start":"2026-02-17T16:08:12.995052Z","end":"2026-02-17T16:08:14.435203Z","steps":["trace[1153583257] 'read index received' (duration: 648.103922ms)","trace[1153583257] 'applied index is now lower than readState.Index' (duration: 792.046892ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:08:14.435342Z","caller":"traceutil/trace.go:171","msg":"trace[714803676] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.521811187s","start":"2026-02-17T16:08:12.913525Z","end":"2026-02-17T16:08:14.435336Z","steps":["trace[714803676] 'process raft request' (duration: 1.311120365s)","trace[714803676] 'compare' (duration: 210.301581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:14.435372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:12.913521Z","time spent":"1.521835708s","remote":"127.0.0.1:49184","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-17T16:08:14.435457Z","caller":"traceutil/trace.go:171","msg":"trace[216727484] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"1.521903029s","start":"2026-02-17T16:08:12.91355Z","end":"2026-02-17T16:08:14.435453Z","steps":["trace[216727484] 'process raft request' (duration: 1.521628082s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:14.435479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:12.913548Z","time spent":"1.521919221s","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:08:14.435575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440523854s","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-17T16:08:14.435588Z","caller":"traceutil/trace.go:171","msg":"trace[2105671465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.440568915s","start":"2026-02-17T16:08:12.995015Z","end":"2026-02-17T16:08:14.435584Z","steps":["trace[2105671465] 'agreement among raft nodes before linearized reading' (duration: 1.440512734s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:14.435602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:12.995002Z","time spent":"1.440597245s","remote":"127.0.0.1:49184","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-17T16:08:14.435678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125939991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:08:14.435689Z","caller":"traceutil/trace.go:171","msg":"trace[699512879] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:911; }","duration":"1.125986962s","start":"2026-02-17T16:08:13.309699Z","end":"2026-02-17T16:08:14.435686Z","steps":["trace[699512879] 'agreement among raft nodes before linearized reading' (duration: 1.125963371s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:14.435701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:13.309679Z","time spent":"1.126018372s","remote":"127.0.0.1:49322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:08:14.435827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.861326ms","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-17T16:08:14.435841Z","caller":"traceutil/trace.go:171","msg":"trace[875125541] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:911; }","duration":"994.898467ms","start":"2026-02-17T16:08:13.440937Z","end":"2026-02-17T16:08:14.435835Z","steps":["trace[875125541] 'agreement among raft nodes before linearized reading' (duration: 994.873836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:14.435854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:13.440923Z","time spent":"994.926547ms","remote":"127.0.0.1:49364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-17T16:08:16.043661Z","caller":"traceutil/trace.go:171","msg":"trace[698975826] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"226.959013ms","start":"2026-02-17T16:08:15.816685Z","end":"2026-02-17T16:08:16.043644Z","steps":["trace[698975826] 'read index received' (duration: 226.818319ms)","trace[698975826] 'applied index is now lower than readState.Index' (duration: 140.044µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:16.043826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.121698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:08:16.043844Z","caller":"traceutil/trace.go:171","msg":"trace[1709229360] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:914; }","duration":"227.18051ms","start":"2026-02-17T16:08:15.816658Z","end":"2026-02-17T16:08:16.043838Z","steps":["trace[1709229360] 'agreement among raft nodes before linearized reading' (duration: 227.066276ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:16.044093Z","caller":"traceutil/trace.go:171","msg":"trace[186237706] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"362.628046ms","start":"2026-02-17T16:08:15.681458Z","end":"2026-02-17T16:08:16.044087Z","steps":["trace[186237706] 'process raft request' (duration: 362.094621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:16.044147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:15.681445Z","time spent":"362.661106ms","remote":"127.0.0.1:49184","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-17T16:08:16.602998Z","caller":"traceutil/trace.go:171","msg":"trace[1864083822] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"143.509259ms","start":"2026-02-17T16:08:16.459476Z","end":"2026-02-17T16:08:16.602985Z","steps":["trace[1864083822] 'read index received' (duration: 136.940967ms)","trace[1864083822] 'applied index is now lower than readState.Index' (duration: 6.567412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:16.60307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:16.055278Z","time spent":"547.79016ms","remote":"127.0.0.1:48952","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T16:08:16.603225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.737466ms","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-17T16:08:16.603244Z","caller":"traceutil/trace.go:171","msg":"trace[770029020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"143.802157ms","start":"2026-02-17T16:08:16.459436Z","end":"2026-02-17T16:08:16.603239Z","steps":["trace[770029020] 'agreement among raft nodes before linearized reading' (duration: 143.719845ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:16.80286Z","caller":"traceutil/trace.go:171","msg":"trace[1776020581] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"194.647867ms","start":"2026-02-17T16:08:16.608191Z","end":"2026-02-17T16:08:16.802839Z","steps":["trace[1776020581] 'process raft request' (duration: 100.102736ms)","trace[1776020581] 'compare' (duration: 94.234363ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:08:16.803064Z","caller":"traceutil/trace.go:171","msg":"trace[488024556] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:951; }","duration":"141.294908ms","start":"2026-02-17T16:08:16.661762Z","end":"2026-02-17T16:08:16.803057Z","steps":["trace[488024556] 'read index received' (duration: 46.538731ms)","trace[488024556] 'applied index is now lower than readState.Index' (duration: 94.754877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:16.803168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.37708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:08:16.80319Z","caller":"traceutil/trace.go:171","msg":"trace[160285571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"141.446863ms","start":"2026-02-17T16:08:16.661737Z","end":"2026-02-17T16:08:16.803184Z","steps":["trace[160285571] 'agreement among raft nodes before linearized reading' (duration: 141.34542ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:16.803308Z","caller":"traceutil/trace.go:171","msg":"trace[2129394543] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"194.904715ms","start":"2026-02-17T16:08:16.608397Z","end":"2026-02-17T16:08:16.803302Z","steps":["trace[2129394543] 'process raft request' (duration: 194.600797ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:16.803343Z","caller":"traceutil/trace.go:171","msg":"trace[1594129733] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"194.991507ms","start":"2026-02-17T16:08:16.60832Z","end":"2026-02-17T16:08:16.803312Z","steps":["trace[1594129733] 'process raft request' (duration: 194.469073ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:16.803418Z","caller":"traceutil/trace.go:171","msg":"trace[1769464012] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"194.964196ms","start":"2026-02-17T16:08:16.608445Z","end":"2026-02-17T16:08:16.80341Z","steps":["trace[1769464012] 'process raft request' (duration: 194.586386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:17.578319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.379675ms","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-17T16:08:17.578366Z","caller":"traceutil/trace.go:171","msg":"trace[349986860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"566.470957ms","start":"2026-02-17T16:08:17.011884Z","end":"2026-02-17T16:08:17.578355Z","steps":["trace[349986860] 'range keys from in-memory index tree' (duration: 566.22624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:17.578386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:17.011854Z","time spent":"566.527939ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T16:08:17.800906Z","caller":"traceutil/trace.go:171","msg":"trace[990062582] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"219.046674ms","start":"2026-02-17T16:08:17.581842Z","end":"2026-02-17T16:08:17.800889Z","steps":["trace[990062582] 'process raft request' (duration: 218.968771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:18.224797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.705205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:08:18.22485Z","caller":"traceutil/trace.go:171","msg":"trace[1495343155] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:919; }","duration":"165.792067ms","start":"2026-02-17T16:08:18.059044Z","end":"2026-02-17T16:08:18.224836Z","steps":["trace[1495343155] 'count revisions from in-memory index tree' (duration: 165.655203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:18.225002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.359546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T16:08:18.225015Z","caller":"traceutil/trace.go:171","msg":"trace[104675712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"165.381986ms","start":"2026-02-17T16:08:18.059629Z","end":"2026-02-17T16:08:18.225011Z","steps":["trace[104675712] 'range keys from in-memory index tree' (duration: 165.292584ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:18.418408Z","caller":"traceutil/trace.go:171","msg":"trace[1629607466] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"190.674316ms","start":"2026-02-17T16:08:18.227719Z","end":"2026-02-17T16:08:18.418393Z","steps":["trace[1629607466] 'process raft request' (duration: 190.387908ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:20.68265Z","caller":"traceutil/trace.go:171","msg":"trace[1832283742] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"180.489955ms","start":"2026-02-17T16:08:20.502143Z","end":"2026-02-17T16:08:20.682633Z","steps":["trace[1832283742] 'process raft request' (duration: 180.024412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:21.1322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.997227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:08:21.133171Z","caller":"traceutil/trace.go:171","msg":"trace[1321126790] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"315.713014ms","start":"2026-02-17T16:08:20.817442Z","end":"2026-02-17T16:08:21.133155Z","steps":["trace[1321126790] 'process raft request' (duration: 185.669898ms)","trace[1321126790] 'compare' (duration: 128.904165ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:08:21.133558Z","caller":"traceutil/trace.go:171","msg":"trace[500794369] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"311.806286ms","start":"2026-02-17T16:08:20.820467Z","end":"2026-02-17T16:08:21.132274Z","steps":["trace[500794369] 'read index received' (duration: 71.733329ms)","trace[500794369] 'applied index is now lower than readState.Index' (duration: 240.071877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:21.133825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.345798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-17T16:08:21.134451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:20.817432Z","time spent":"315.786546ms","remote":"127.0.0.1:49184","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-17T16:08:21.133864Z","caller":"traceutil/trace.go:171","msg":"trace[1839792561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:937; }","duration":"313.40189ms","start":"2026-02-17T16:08:20.820452Z","end":"2026-02-17T16:08:21.133854Z","steps":["trace[1839792561] 'agreement among raft nodes before linearized reading' (duration: 313.146603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:21.134735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:20.820445Z","time spent":"314.275754ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:08:21.425786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.463491ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T16:08:21.426061Z","caller":"traceutil/trace.go:171","msg":"trace[906550259] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"287.158072ms","start":"2026-02-17T16:08:21.138886Z","end":"2026-02-17T16:08:21.426044Z","steps":["trace[906550259] 'process raft request' (duration: 287.002808ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:22.134768Z","caller":"traceutil/trace.go:171","msg":"trace[1958491255] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"319.870019ms","start":"2026-02-17T16:08:21.814879Z","end":"2026-02-17T16:08:22.134749Z","steps":["trace[1958491255] 'process raft request' (duration: 319.777636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:22.134866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:21.814863Z","time spent":"319.952791ms","remote":"127.0.0.1:49184","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-17T16:08:24.514472Z","caller":"traceutil/trace.go:171","msg":"trace[690465281] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"175.427034ms","start":"2026-02-17T16:08:24.339023Z","end":"2026-02-17T16:08:24.514451Z","steps":["trace[690465281] 'process raft request' (duration: 175.306341ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:28.312721Z","caller":"traceutil/trace.go:171","msg":"trace[1661240803] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"139.721614ms","start":"2026-02-17T16:08:28.172983Z","end":"2026-02-17T16:08:28.312705Z","steps":["trace[1661240803] 'process raft request' (duration: 139.617681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:28.787675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.192214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:08:28.787728Z","caller":"traceutil/trace.go:171","msg":"trace[263890000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"119.275576ms","start":"2026-02-17T16:08:28.668439Z","end":"2026-02-17T16:08:28.787715Z","steps":["trace[263890000] 'range keys from in-memory index tree' (duration: 119.145292ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:39.055385Z","caller":"traceutil/trace.go:171","msg":"trace[1415648824] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"109.04743ms","start":"2026-02-17T16:08:38.946313Z","end":"2026-02-17T16:08:39.05536Z","steps":["trace[1415648824] 'process raft request' (duration: 108.268718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:39.734654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763478094216122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:08:40.235268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763478094216122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:08:40.280944Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.216371213s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:08:40.286858Z","caller":"traceutil/trace.go:171","msg":"trace[1912195899] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.22232914s","start":"2026-02-17T16:08:39.064484Z","end":"2026-02-17T16:08:40.286813Z","steps":["trace[1912195899] 'process raft request' (duration: 1.222103934s)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:40.287546Z","caller":"traceutil/trace.go:171","msg":"trace[1188061817] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"1.05346064s","start":"2026-02-17T16:08:39.234067Z","end":"2026-02-17T16:08:40.287527Z","steps":["trace[1188061817] 'read index received' (duration: 1.053315066s)","trace[1188061817] 'applied index is now lower than readState.Index' (duration: 144.804µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:40.289933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:39.064468Z","time spent":"1.225400127s","remote":"127.0.0.1:49184","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-17T16:08:40.287714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053624606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-17T16:08:40.290172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.387663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:08:40.290186Z","caller":"traceutil/trace.go:171","msg":"trace[44571455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"1.056217607s","start":"2026-02-17T16:08:39.233959Z","end":"2026-02-17T16:08:40.290176Z","steps":["trace[44571455] 'agreement among raft nodes before linearized reading' (duration: 1.053615855s)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:40.290201Z","caller":"traceutil/trace.go:171","msg":"trace[1373231161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"628.444105ms","start":"2026-02-17T16:08:39.661747Z","end":"2026-02-17T16:08:40.290192Z","steps":["trace[1373231161] 'agreement among raft nodes before linearized reading' (duration: 628.360572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:40.290224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:39.661731Z","time spent":"628.488106ms","remote":"127.0.0.1:48906","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-17T16:08:40.287746Z","caller":"traceutil/trace.go:171","msg":"trace[435005771] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"583.775971ms","start":"2026-02-17T16:08:39.703961Z","end":"2026-02-17T16:08:40.287737Z","steps":["trace[435005771] 'process raft request' (duration: 583.466882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:40.290263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:39.703942Z","time spent":"586.301871ms","remote":"127.0.0.1:49184","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-17T16:08:40.290636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.170107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T16:08:40.290701Z","caller":"traceutil/trace.go:171","msg":"trace[1331879215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1022; }","duration":"118.27303ms","start":"2026-02-17T16:08:40.172421Z","end":"2026-02-17T16:08:40.290694Z","steps":["trace[1331879215] 'agreement among raft nodes before linearized reading' (duration: 118.158987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:40.290227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:08:39.233915Z","time spent":"1.05629332s","remote":"127.0.0.1:49184","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":"info","ts":"2026-02-17T16:11:53.775015Z","caller":"traceutil/trace.go:171","msg":"trace[1705413130] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1940; }","duration":"256.338139ms","start":"2026-02-17T16:11:53.518659Z","end":"2026-02-17T16:11:53.774997Z","steps":["trace[1705413130] 'read index received' (duration: 247.175608ms)","trace[1705413130] 'applied index is now lower than readState.Index' (duration: 9.161911ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:11:53.775224Z","caller":"traceutil/trace.go:171","msg":"trace[378393804] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"300.216601ms","start":"2026-02-17T16:11:53.474984Z","end":"2026-02-17T16:11:53.775201Z","steps":["trace[378393804] 'process raft request' (duration: 290.838404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.775298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:53.474972Z","time spent":"300.271912ms","remote":"127.0.0.1:49184","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-17T16:11:53.775413Z","caller":"traceutil/trace.go:171","msg":"trace[330080530] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"297.898887ms","start":"2026-02-17T16:11:53.477508Z","end":"2026-02-17T16:11:53.775407Z","steps":["trace[330080530] 'process raft request' (duration: 297.458175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.77548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.818992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:11:53.7755Z","caller":"traceutil/trace.go:171","msg":"trace[683301811] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1860; }","duration":"256.877054ms","start":"2026-02-17T16:11:53.518616Z","end":"2026-02-17T16:11:53.775493Z","steps":["trace[683301811] 'agreement among raft nodes before linearized reading' (duration: 256.841163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.775678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.298333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-17T16:11:53.775696Z","caller":"traceutil/trace.go:171","msg":"trace[1272983758] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1860; }","duration":"216.343754ms","start":"2026-02-17T16:11:53.559347Z","end":"2026-02-17T16:11:53.77569Z","steps":["trace[1272983758] 'agreement among raft nodes before linearized reading' (duration: 216.254022ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:04.074575Z","caller":"traceutil/trace.go:171","msg":"trace[1685358876] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"121.560239ms","start":"2026-02-17T16:12:03.952998Z","end":"2026-02-17T16:12:04.074559Z","steps":["trace[1685358876] 'process raft request' (duration: 109.978252ms)","trace[1685358876] 'compare' (duration: 11.153915ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:14.833068Z","caller":"traceutil/trace.go:171","msg":"trace[279615883] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"428.282518ms","start":"2026-02-17T16:12:14.404767Z","end":"2026-02-17T16:12:14.833049Z","steps":["trace[279615883] 'read index received' (duration: 376.652304ms)","trace[279615883] 'applied index is now lower than readState.Index' (duration: 51.629184ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:14.833265Z","caller":"traceutil/trace.go:171","msg":"trace[2026817171] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"432.001789ms","start":"2026-02-17T16:12:14.401238Z","end":"2026-02-17T16:12:14.83324Z","steps":["trace[2026817171] 'process raft request' (duration: 380.235901ms)","trace[2026817171] 'compare' (duration: 51.47406ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:14.833377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:14.401227Z","time spent":"432.087031ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:12:14.83361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.834103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/\" range_end:\"/registry/serviceaccounts/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-02-17T16:12:14.833645Z","caller":"traceutil/trace.go:171","msg":"trace[1043371725] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/; range_end:/registry/serviceaccounts/kube-node-lease0; response_count:1; response_revision:1953; }","duration":"428.884724ms","start":"2026-02-17T16:12:14.40475Z","end":"2026-02-17T16:12:14.833634Z","steps":["trace[1043371725] 'agreement among raft nodes before linearized reading' (duration: 428.814962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:14.833676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:14.404744Z","time spent":"428.923795ms","remote":"127.0.0.1:49130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":210,"request content":"key:\"/registry/serviceaccounts/kube-node-lease/\" range_end:\"/registry/serviceaccounts/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-02-17T16:12:14.845707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.025624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:14.84577Z","caller":"traceutil/trace.go:171","msg":"trace[1281645648] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1954; }","duration":"423.134937ms","start":"2026-02-17T16:12:14.422622Z","end":"2026-02-17T16:12:14.845757Z","steps":["trace[1281645648] 'agreement among raft nodes before linearized reading' (duration: 422.943622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:14.845818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:14.422607Z","time spent":"423.204028ms","remote":"127.0.0.1:49016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-02-17T16:12:14.846151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.110121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:14.846178Z","caller":"traceutil/trace.go:171","msg":"trace[1342219646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1954; }","duration":"184.191524ms","start":"2026-02-17T16:12:14.66198Z","end":"2026-02-17T16:12:14.846172Z","steps":["trace[1342219646] 'agreement among raft nodes before linearized reading' (duration: 184.155633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:14.846304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.722023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-17T16:12:14.846322Z","caller":"traceutil/trace.go:171","msg":"trace[1985063983] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1954; }","duration":"381.760493ms","start":"2026-02-17T16:12:14.464556Z","end":"2026-02-17T16:12:14.846317Z","steps":["trace[1985063983] 'agreement among raft nodes before linearized reading' (duration: 381.696771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:14.846333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:14.464544Z","time spent":"381.786994ms","remote":"127.0.0.1:49080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1266,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"info","ts":"2026-02-17T16:12:16.785318Z","caller":"traceutil/trace.go:171","msg":"trace[1964740606] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2050; }","duration":"127.723638ms","start":"2026-02-17T16:12:16.657578Z","end":"2026-02-17T16:12:16.785302Z","steps":["trace[1964740606] 'read index received' (duration: 127.625665ms)","trace[1964740606] 'applied index is now lower than readState.Index' (duration: 97.263µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:16.785436Z","caller":"traceutil/trace.go:171","msg":"trace[6465402] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"145.801333ms","start":"2026-02-17T16:12:16.639627Z","end":"2026-02-17T16:12:16.785428Z","steps":["trace[6465402] 'process raft request' (duration: 145.573966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:16.785754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.1632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:16.785821Z","caller":"traceutil/trace.go:171","msg":"trace[43360716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1964; }","duration":"128.253722ms","start":"2026-02-17T16:12:16.657559Z","end":"2026-02-17T16:12:16.785813Z","steps":["trace[43360716] 'agreement among raft nodes before linearized reading' (duration: 128.16994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:25.069629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.580519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:12:25.069857Z","caller":"traceutil/trace.go:171","msg":"trace[1100646762] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2080; }","duration":"482.209205ms","start":"2026-02-17T16:12:24.587631Z","end":"2026-02-17T16:12:25.069841Z","steps":["trace[1100646762] 'read index received' (duration: 58.386029ms)","trace[1100646762] 'applied index is now lower than readState.Index' (duration: 423.821916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:25.070729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.176048ms","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-17T16:12:25.071249Z","caller":"traceutil/trace.go:171","msg":"trace[245421326] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:1993; }","duration":"469.724463ms","start":"2026-02-17T16:12:24.601511Z","end":"2026-02-17T16:12:25.071235Z","steps":["trace[245421326] 'agreement among raft nodes before linearized reading' (duration: 469.164978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:25.070974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.334476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-17T16:12:25.071444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:24.601503Z","time spent":"469.924588ms","remote":"127.0.0.1:49130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"info","ts":"2026-02-17T16:12:25.071641Z","caller":"traceutil/trace.go:171","msg":"trace[2115942878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1993; }","duration":"483.798039ms","start":"2026-02-17T16:12:24.587598Z","end":"2026-02-17T16:12:25.071396Z","steps":["trace[2115942878] 'agreement among raft nodes before linearized reading' (duration: 483.322536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:25.071696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:24.587584Z","time spent":"484.099376ms","remote":"127.0.0.1:49130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-02-17T16:12:25.071088Z","caller":"traceutil/trace.go:171","msg":"trace[1165673380] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"524.196824ms","start":"2026-02-17T16:12:24.54602Z","end":"2026-02-17T16:12:25.070217Z","steps":["trace[1165673380] 'process raft request' (duration: 99.957347ms)","trace[1165673380] 'compare' (duration: 423.200329ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:25.072106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:24.54601Z","time spent":"526.047096ms","remote":"127.0.0.1:49184","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-17T16:12:25.0711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.080993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-17T16:12:25.072581Z","caller":"traceutil/trace.go:171","msg":"trace[1406285647] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1993; }","duration":"165.583944ms","start":"2026-02-17T16:12:24.906982Z","end":"2026-02-17T16:12:25.072566Z","steps":["trace[1406285647] 'agreement among raft nodes before linearized reading' (duration: 164.035242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:25.071148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.9808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:12:25.072931Z","caller":"traceutil/trace.go:171","msg":"trace[2073957958] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1993; }","duration":"178.356064ms","start":"2026-02-17T16:12:24.894563Z","end":"2026-02-17T16:12:25.072919Z","steps":["trace[2073957958] 'agreement among raft nodes before linearized reading' (duration: 176.558055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:25.071179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.041161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:25.073376Z","caller":"traceutil/trace.go:171","msg":"trace[305461514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1993; }","duration":"411.232261ms","start":"2026-02-17T16:12:24.662132Z","end":"2026-02-17T16:12:25.073364Z","steps":["trace[305461514] 'agreement among raft nodes before linearized reading' (duration: 409.032481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:25.073454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:24.662051Z","time spent":"411.388895ms","remote":"127.0.0.1:48906","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-17T16:12:29.857804Z","caller":"traceutil/trace.go:171","msg":"trace[107781857] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"115.659377ms","start":"2026-02-17T16:12:29.742119Z","end":"2026-02-17T16:12:29.857778Z","steps":["trace[107781857] 'process raft request' (duration: 115.180564ms)"],"step_count":1}