{"level":"warn","ts":"2026-02-20T18:23:31.917368Z","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-20T18:23:31.917472Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-20T18:23:31.917545Z","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-20T18:23:31.91756Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-20T18:23:31.917586Z","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-20T18:23:31.918386Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-02-20T18:23:31.918488Z","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":8,"max-cpu-available":8,"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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-20T18:23:31.924198Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.286965ms"} {"level":"info","ts":"2026-02-20T18:23:31.932344Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-02-20T18:23:31.932422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T18:23:31.932448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-02-20T18:23:31.932464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T18:23:31.932484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-02-20T18:23:31.932529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-02-20T18:23:31.939453Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T18:23:31.940477Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T18:23:31.941726Z","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-20T18:23:31.943851Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T18:23:31.944963Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T18:23:31.944984Z","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-20T18:23:31.945186Z","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-20T18:23:31.945229Z","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-20T18:23:31.946156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-02-20T18:23:31.946285Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-02-20T18:23:31.951747Z","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-20T18:23:31.951888Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-20T18:23:31.951956Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-02-20T18:23:31.952118Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T18:23:31.952151Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T18:23:32.733688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T18:23:32.733777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T18:23:32.733821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-02-20T18:23:32.73385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-02-20T18:23:32.733865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-20T18:23:32.733885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-02-20T18:23:32.733908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-02-20T18:23:32.735144Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:23:32.735135Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T18:23:32.735171Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:23:32.735221Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:23:32.735435Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T18:23:32.735488Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T18:23:32.736634Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:23:32.73682Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:23:32.736949Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:23:32.738684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-02-20T18:23:32.74136Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T18:23:46.418238Z","caller":"traceutil/trace.go:171","msg":"trace[234166680] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"168.766573ms","start":"2026-02-20T18:23:46.249448Z","end":"2026-02-20T18:23:46.418215Z","steps":["trace[234166680] 'read index received' (duration: 168.582098ms)","trace[234166680] 'applied index is now lower than readState.Index' (duration: 183.725µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:46.418306Z","caller":"traceutil/trace.go:171","msg":"trace[1927168067] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"209.670981ms","start":"2026-02-20T18:23:46.208609Z","end":"2026-02-20T18:23:46.41828Z","steps":["trace[1927168067] 'process raft request' (duration: 209.472335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:46.418448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.978098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-20T18:23:46.418581Z","caller":"traceutil/trace.go:171","msg":"trace[1120866890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"169.184784ms","start":"2026-02-20T18:23:46.249377Z","end":"2026-02-20T18:23:46.418562Z","steps":["trace[1120866890] 'agreement among raft nodes before linearized reading' (duration: 168.975759ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:52.675194Z","caller":"traceutil/trace.go:171","msg":"trace[1359607356] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"224.210129ms","start":"2026-02-20T18:23:52.450948Z","end":"2026-02-20T18:23:52.675158Z","steps":["trace[1359607356] 'read index received' (duration: 223.988343ms)","trace[1359607356] 'applied index is now lower than readState.Index' (duration: 215.966µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:52.675294Z","caller":"traceutil/trace.go:171","msg":"trace[972903727] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"225.950374ms","start":"2026-02-20T18:23:52.449314Z","end":"2026-02-20T18:23:52.675264Z","steps":["trace[972903727] 'process raft request' (duration: 225.674057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:52.675388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.560595ms","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-20T18:23:52.675424Z","caller":"traceutil/trace.go:171","msg":"trace[1616314102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:451; }","duration":"220.630467ms","start":"2026-02-20T18:23:52.454783Z","end":"2026-02-20T18:23:52.675413Z","steps":["trace[1616314102] 'agreement among raft nodes before linearized reading' (duration: 220.545424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:52.675492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.846578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:23:52.675466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.500706ms","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-20T18:23:52.675539Z","caller":"traceutil/trace.go:171","msg":"trace[458050432] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:451; }","duration":"181.916221ms","start":"2026-02-20T18:23:52.493607Z","end":"2026-02-20T18:23:52.675524Z","steps":["trace[458050432] 'agreement among raft nodes before linearized reading' (duration: 181.844239ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:52.675561Z","caller":"traceutil/trace.go:171","msg":"trace[234243485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:451; }","duration":"224.63041ms","start":"2026-02-20T18:23:52.450907Z","end":"2026-02-20T18:23:52.675537Z","steps":["trace[234243485] 'agreement among raft nodes before linearized reading' (duration: 224.380343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:59.301841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:23:59.802504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:00.30326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:00.80369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:01.304869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:01.493818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000062843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:24:01.493914Z","caller":"traceutil/trace.go:171","msg":"trace[766600372] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000206547s","start":"2026-02-20T18:23:59.493685Z","end":"2026-02-20T18:24:01.493891Z","steps":["trace[766600372] 'agreement among raft nodes before linearized reading' (duration: 2.000082964s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:01.49398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:59.493673Z","time spent":"2.000293229s","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:24:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:24:01.805982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:02.306856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:02.79721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.092002082s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:24:02.797648Z","caller":"traceutil/trace.go:171","msg":"trace[1515281898] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"4.092524714s","start":"2026-02-20T18:23:58.705097Z","end":"2026-02-20T18:24:02.797622Z","steps":["trace[1515281898] 'process raft request' (duration: 4.092374781s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.798368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:58.705077Z","time spent":"4.092693889s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:03.494175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000072459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:24:03.494284Z","caller":"traceutil/trace.go:171","msg":"trace[2134167289] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000237093s","start":"2026-02-20T18:24:01.494026Z","end":"2026-02-20T18:24:03.494263Z","steps":["trace[2134167289] 'agreement among raft nodes before linearized reading' (duration: 2.00010612s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:03.494345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:01.494009Z","time spent":"2.000325285s","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:24:03 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:24:04.012417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998628606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:24:04.012601Z","caller":"traceutil/trace.go:171","msg":"trace[652704435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.998777829s","start":"2026-02-20T18:23:59.013712Z","end":"2026-02-20T18:24:04.01249Z","steps":["trace[652704435] 'agreement among raft nodes before linearized reading' (duration: 4.998659516s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:04.012822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:59.013694Z","time spent":"4.999109917s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:24:04.071083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999696827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:24:04.071171Z","caller":"traceutil/trace.go:171","msg":"trace[344420396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999837821s","start":"2026-02-20T18:23:59.071313Z","end":"2026-02-20T18:24:04.071151Z","steps":["trace[344420396] 'agreement among raft nodes before linearized reading' (duration: 4.999721988s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:04.071235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:59.071297Z","time spent":"4.999918752s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/20 18:24:04 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:24:04.825808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193858678s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:04.826033Z","caller":"traceutil/trace.go:171","msg":"trace[471389741] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:466; }","duration":"1.194098473s","start":"2026-02-20T18:24:03.631906Z","end":"2026-02-20T18:24:04.826004Z","steps":["trace[471389741] 'range keys from in-memory index tree' (duration: 1.193837877s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:04.826609Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.328027224s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:24:04.828225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372214807s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-20T18:24:04.828511Z","caller":"traceutil/trace.go:171","msg":"trace[1671445162] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:481; }","duration":"6.027327315s","start":"2026-02-20T18:23:58.80116Z","end":"2026-02-20T18:24:04.828487Z","steps":["trace[1671445162] 'read index received' (duration: 3.996387501s)","trace[1671445162] 'applied index is now lower than readState.Index' (duration: 2.030937874s)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:04.828587Z","caller":"traceutil/trace.go:171","msg":"trace[811690444] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"428.607093ms","start":"2026-02-20T18:24:04.399957Z","end":"2026-02-20T18:24:04.828564Z","steps":["trace[811690444] 'process raft request' (duration: 428.515631ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:04.828753Z","caller":"traceutil/trace.go:171","msg":"trace[171530638] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"1.330146592s","start":"2026-02-20T18:24:03.498439Z","end":"2026-02-20T18:24:04.828585Z","steps":["trace[171530638] 'process raft request' (duration: 1.329919027s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:04.828802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:04.399929Z","time spent":"428.713475ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:04.828942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:03.498405Z","time spent":"1.330413178s","remote":"127.0.0.1:37500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:24:04.829013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326810505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:04.829087Z","caller":"traceutil/trace.go:171","msg":"trace[1986350624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"1.326883077s","start":"2026-02-20T18:24:03.502159Z","end":"2026-02-20T18:24:04.829042Z","steps":["trace[1986350624] 'agreement among raft nodes before linearized reading' (duration: 1.326793375s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:04.829155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:03.502147Z","time spent":"1.326997319s","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:04.829615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.02845394s","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-20T18:24:04.829693Z","caller":"traceutil/trace.go:171","msg":"trace[1230704242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"6.028549642s","start":"2026-02-20T18:23:58.801126Z","end":"2026-02-20T18:24:04.829675Z","steps":["trace[1230704242] 'agreement among raft nodes before linearized reading' (duration: 6.027466887s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:04.829728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:58.801115Z","time spent":"6.028604184s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:06.685175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:07.185707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:08.127744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:08.196165Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.532409883s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:24:08.196584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:05.663642Z","time spent":"2.532937186s","remote":"127.0.0.1:37450","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T18:24:08.20119Z","caller":"traceutil/trace.go:171","msg":"trace[708756531] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:487; }","duration":"2.016661644s","start":"2026-02-20T18:24:06.184508Z","end":"2026-02-20T18:24:08.20117Z","steps":["trace[708756531] 'read index received' (duration: 2.01197489s)","trace[708756531] 'applied index is now lower than readState.Index' (duration: 4.685324ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:08.201323Z","caller":"traceutil/trace.go:171","msg":"trace[1139151792] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"525.835358ms","start":"2026-02-20T18:24:07.675447Z","end":"2026-02-20T18:24:08.201282Z","steps":["trace[1139151792] 'process raft request' (duration: 525.586802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.201395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.016867509s","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-20T18:24:08.201439Z","caller":"traceutil/trace.go:171","msg":"trace[984467032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"2.01695618s","start":"2026-02-20T18:24:06.184474Z","end":"2026-02-20T18:24:08.20143Z","steps":["trace[984467032] 'agreement among raft nodes before linearized reading' (duration: 2.016851648s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.201464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.184458Z","time spent":"2.017001712s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:24:08.201663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:07.675422Z","time spent":"525.988412ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:08.214683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.03007318s","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-20T18:24:08.214889Z","caller":"traceutil/trace.go:171","msg":"trace[449009821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:472; }","duration":"2.030337466s","start":"2026-02-20T18:24:06.18454Z","end":"2026-02-20T18:24:08.214878Z","steps":["trace[449009821] 'agreement among raft nodes before linearized reading' (duration: 2.030082101s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.214778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375810763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:24:08.214976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.184536Z","time spent":"2.030430838s","remote":"127.0.0.1:37696","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-20T18:24:08.215009Z","caller":"traceutil/trace.go:171","msg":"trace[59377919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"1.376086379s","start":"2026-02-20T18:24:06.838907Z","end":"2026-02-20T18:24:08.214993Z","steps":["trace[59377919] 'agreement among raft nodes before linearized reading' (duration: 1.375829113s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.215191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.838878Z","time spent":"1.376296293s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:08.214811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375344862s","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-20T18:24:08.21528Z","caller":"traceutil/trace.go:171","msg":"trace[735633001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"1.375847043s","start":"2026-02-20T18:24:06.839413Z","end":"2026-02-20T18:24:08.21526Z","steps":["trace[735633001] 'agreement among raft nodes before linearized reading' (duration: 1.37529511s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.215328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.839402Z","time spent":"1.375914705s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:08.214812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72111609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:24:08.214799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448057761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:08.215566Z","caller":"traceutil/trace.go:171","msg":"trace[1601417446] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:472; }","duration":"1.448878348s","start":"2026-02-20T18:24:06.766632Z","end":"2026-02-20T18:24:08.21551Z","steps":["trace[1601417446] 'agreement among raft nodes before linearized reading' (duration: 1.44804869s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.21496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.805965482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:08.215675Z","caller":"traceutil/trace.go:171","msg":"trace[727187428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"1.806738159s","start":"2026-02-20T18:24:06.408904Z","end":"2026-02-20T18:24:08.215642Z","steps":["trace[727187428] 'agreement among raft nodes before linearized reading' (duration: 1.805918351s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.215666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.766597Z","time spent":"1.449047212s","remote":"127.0.0.1:37628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-02-20T18:24:08.215389Z","caller":"traceutil/trace.go:171","msg":"trace[1569341509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"1.721753925s","start":"2026-02-20T18:24:06.493622Z","end":"2026-02-20T18:24:08.215376Z","steps":["trace[1569341509] 'agreement among raft nodes before linearized reading' (duration: 1.721135562s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.21572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.408887Z","time spent":"1.806822021s","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:08.215751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.493594Z","time spent":"1.722132953s","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:10.72577Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237205,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:11.226855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237205,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:11.966987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237205,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:12.468013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237205,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:12.942995Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.719056715s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:24:12.943449Z","caller":"traceutil/trace.go:171","msg":"trace[2145590084] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"2.719644197s","start":"2026-02-20T18:24:10.223785Z","end":"2026-02-20T18:24:12.943429Z","steps":["trace[2145590084] 'process raft request' (duration: 2.719466863s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:12.943587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:10.223718Z","time spent":"2.719787521s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:13.22289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.47284ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-20T18:24:13.223004Z","caller":"traceutil/trace.go:171","msg":"trace[1033412515] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:528; }","duration":"2.997780456s","start":"2026-02-20T18:24:10.225203Z","end":"2026-02-20T18:24:13.222984Z","steps":["trace[1033412515] 'read index received' (duration: 2.718045953s)","trace[1033412515] 'applied index is now lower than readState.Index' (duration: 279.732763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:13.223177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997957369s","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-20T18:24:13.223219Z","caller":"traceutil/trace.go:171","msg":"trace[643409846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"2.99803495s","start":"2026-02-20T18:24:10.225171Z","end":"2026-02-20T18:24:13.223206Z","steps":["trace[643409846] 'agreement among raft nodes before linearized reading' (duration: 2.997922918s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:13.223255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:10.225157Z","time spent":"2.998089512s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:24:13.223321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.171225721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"warn","ts":"2026-02-20T18:24:13.223338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732393379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:24:13.223336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997987s","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-20T18:24:13.223393Z","caller":"traceutil/trace.go:171","msg":"trace[2054089123] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:511; }","duration":"2.171354704s","start":"2026-02-20T18:24:11.052018Z","end":"2026-02-20T18:24:13.223373Z","steps":["trace[2054089123] 'agreement among raft nodes before linearized reading' (duration: 2.171196821s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:13.223406Z","caller":"traceutil/trace.go:171","msg":"trace[1150277573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"1.73245825s","start":"2026-02-20T18:24:11.490928Z","end":"2026-02-20T18:24:13.223386Z","steps":["trace[1150277573] 'agreement among raft nodes before linearized reading' (duration: 1.732366328s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:13.223441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:11.051999Z","time spent":"2.171432505s","remote":"127.0.0.1:37598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4043,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-20T18:24:13.223452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:11.490879Z","time spent":"1.732563432s","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:24:13.223453Z","caller":"traceutil/trace.go:171","msg":"trace[1592794389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"2.998126673s","start":"2026-02-20T18:24:10.225292Z","end":"2026-02-20T18:24:13.223419Z","steps":["trace[1592794389] 'agreement among raft nodes before linearized reading' (duration: 2.997876168s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:13.223512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:10.225234Z","time spent":"2.998268336s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:13.223541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997693473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:24:13.223641Z","caller":"traceutil/trace.go:171","msg":"trace[956114676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"2.997846976s","start":"2026-02-20T18:24:10.225764Z","end":"2026-02-20T18:24:13.223611Z","steps":["trace[956114676] 'agreement among raft nodes before linearized reading' (duration: 2.997639852s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:13.223692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:10.225742Z","time spent":"2.997940548s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:19.607301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.844851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:24:19.607645Z","caller":"traceutil/trace.go:171","msg":"trace[729367652] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"112.459233ms","start":"2026-02-20T18:24:19.49516Z","end":"2026-02-20T18:24:19.607619Z","steps":["trace[729367652] 'read index received' (duration: 10.366049ms)","trace[729367652] 'applied index is now lower than readState.Index' (duration: 102.092024ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:19.60766Z","caller":"traceutil/trace.go:171","msg":"trace[176292323] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"257.414899ms","start":"2026-02-20T18:24:19.350207Z","end":"2026-02-20T18:24:19.607622Z","steps":["trace[176292323] 'process raft request' (duration: 61.12145ms)","trace[176292323] 'compare' (duration: 195.664986ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:19.60773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.564605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:19.607766Z","caller":"traceutil/trace.go:171","msg":"trace[783489256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"112.635536ms","start":"2026-02-20T18:24:19.495118Z","end":"2026-02-20T18:24:19.607753Z","steps":["trace[783489256] 'agreement among raft nodes before linearized reading' (duration: 112.573185ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:19.60784Z","caller":"traceutil/trace.go:171","msg":"trace[817676358] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"257.567363ms","start":"2026-02-20T18:24:19.350239Z","end":"2026-02-20T18:24:19.607806Z","steps":["trace[817676358] 'process raft request' (duration: 257.281597ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:20.569628Z","caller":"traceutil/trace.go:171","msg":"trace[1784614879] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"616.978671ms","start":"2026-02-20T18:24:19.95263Z","end":"2026-02-20T18:24:20.569609Z","steps":["trace[1784614879] 'process raft request' (duration: 616.856818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:20.569749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:19.952607Z","time spent":"617.080202ms","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:21.851761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:22.35241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:22.537214Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.391659177s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:24:22.537759Z","caller":"traceutil/trace.go:171","msg":"trace[2145900827] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"1.186100409s","start":"2026-02-20T18:24:21.351635Z","end":"2026-02-20T18:24:22.537736Z","steps":["trace[2145900827] 'read index received' (duration: 1.185917575s)","trace[2145900827] 'applied index is now lower than readState.Index' (duration: 180.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:22.537909Z","caller":"traceutil/trace.go:171","msg":"trace[1647613518] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.565197997s","start":"2026-02-20T18:24:20.972657Z","end":"2026-02-20T18:24:22.537855Z","steps":["trace[1647613518] 'process raft request' (duration: 1.564943812s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:22.538039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044453126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:22.538118Z","caller":"traceutil/trace.go:171","msg":"trace[1693010326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"1.044587638s","start":"2026-02-20T18:24:21.493507Z","end":"2026-02-20T18:24:22.538095Z","steps":["trace[1693010326] 'agreement among raft nodes before linearized reading' (duration: 1.044418154s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:22.538175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:21.493492Z","time spent":"1.04467105s","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:22.538266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.743786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:24:22.53833Z","caller":"traceutil/trace.go:171","msg":"trace[517087363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"926.838028ms","start":"2026-02-20T18:24:21.611476Z","end":"2026-02-20T18:24:22.538314Z","steps":["trace[517087363] 'agreement among raft nodes before linearized reading' (duration: 926.597803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:22.538366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:21.611456Z","time spent":"926.900979ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:22.538378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.804837ms","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-20T18:24:22.53856Z","caller":"traceutil/trace.go:171","msg":"trace[1597614940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"927.033791ms","start":"2026-02-20T18:24:21.611485Z","end":"2026-02-20T18:24:22.538519Z","steps":["trace[1597614940] 'agreement among raft nodes before linearized reading' (duration: 926.505921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:22.538634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:21.611473Z","time spent":"927.148444ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:22.53858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18666801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:24:22.538795Z","caller":"traceutil/trace.go:171","msg":"trace[1719340955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"1.18717273s","start":"2026-02-20T18:24:21.351606Z","end":"2026-02-20T18:24:22.538778Z","steps":["trace[1719340955] 'agreement among raft nodes before linearized reading' (duration: 1.186288633s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:22.53894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:21.351594Z","time spent":"1.187299182s","remote":"127.0.0.1:37696","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-20T18:24:22.538794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:20.972632Z","time spent":"1.565389201s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:23.04996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:23.550365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:24.051372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938289652623237304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:24.284619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.741591066s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:24:24.400042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.869922ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:24.400191Z","caller":"traceutil/trace.go:171","msg":"trace[1728039863] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:557; }","duration":"767.981024ms","start":"2026-02-20T18:24:23.632143Z","end":"2026-02-20T18:24:24.400124Z","steps":["trace[1728039863] 'range keys from in-memory index tree' (duration: 767.857202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:24.400435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.367878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:24:24.401025Z","caller":"traceutil/trace.go:171","msg":"trace[1290304233] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.858031015s","start":"2026-02-20T18:24:22.542969Z","end":"2026-02-20T18:24:24.401Z","steps":["trace[1290304233] 'process raft request' (duration: 1.742018384s)","trace[1290304233] 'compare' (duration: 114.769627ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:24.401056Z","caller":"traceutil/trace.go:171","msg":"trace[849775673] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.857601827s","start":"2026-02-20T18:24:22.543425Z","end":"2026-02-20T18:24:24.401027Z","steps":["trace[849775673] 'process raft request' (duration: 1.857315821s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:24.401115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:22.542958Z","time spent":"1.858111806s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:24.401183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:22.543394Z","time spent":"1.857718008s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:24.401032Z","caller":"traceutil/trace.go:171","msg":"trace[1323248487] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.857578576s","start":"2026-02-20T18:24:22.543425Z","end":"2026-02-20T18:24:24.401004Z","steps":["trace[1323248487] 'process raft request' (duration: 1.85723507s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:24.401358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:22.543394Z","time spent":"1.857893292s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:24.4431Z","caller":"traceutil/trace.go:171","msg":"trace[1968429765] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:577; }","duration":"1.894205911s","start":"2026-02-20T18:24:22.548837Z","end":"2026-02-20T18:24:24.443043Z","steps":["trace[1968429765] 'read index received' (duration: 1.736074036s)","trace[1968429765] 'applied index is now lower than readState.Index' (duration: 158.130435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:24.443297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.894363344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:24.444209Z","caller":"traceutil/trace.go:171","msg":"trace[2113743155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.895361444s","start":"2026-02-20T18:24:22.548821Z","end":"2026-02-20T18:24:24.444182Z","steps":["trace[2113743155] 'agreement among raft nodes before linearized reading' (duration: 1.894328334s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:24.444308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:22.548814Z","time spent":"1.895478586s","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:24:25.087157Z","caller":"traceutil/trace.go:171","msg":"trace[1543966615] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"133.507876ms","start":"2026-02-20T18:24:24.953626Z","end":"2026-02-20T18:24:25.087134Z","steps":["trace[1543966615] 'process raft request' (duration: 133.362443ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:25.427344Z","caller":"traceutil/trace.go:171","msg":"trace[1337473614] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"462.628172ms","start":"2026-02-20T18:24:24.964693Z","end":"2026-02-20T18:24:25.427321Z","steps":["trace[1337473614] 'process raft request' (duration: 462.463699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.427469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:24.964663Z","time spent":"462.746685ms","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16901,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:27.684511Z","caller":"traceutil/trace.go:171","msg":"trace[299119517] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"457.453721ms","start":"2026-02-20T18:24:27.227039Z","end":"2026-02-20T18:24:27.684493Z","steps":["trace[299119517] 'process raft request' (duration: 456.233088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:27.684617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:27.227002Z","time spent":"457.567833ms","remote":"127.0.0.1:37466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:24:27.693527Z","caller":"traceutil/trace.go:171","msg":"trace[1585485964] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"266.965577ms","start":"2026-02-20T18:24:27.426533Z","end":"2026-02-20T18:24:27.693498Z","steps":["trace[1585485964] 'process raft request' (duration: 266.548569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:29.344618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.664294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-20T18:24:29.344832Z","caller":"traceutil/trace.go:171","msg":"trace[1854496278] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:625; }","duration":"119.883897ms","start":"2026-02-20T18:24:29.224835Z","end":"2026-02-20T18:24:29.344718Z","steps":["trace[1854496278] 'range keys from in-memory index tree' (duration: 119.380678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:38.179543Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.084831445s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:24:38.227079Z","caller":"traceutil/trace.go:171","msg":"trace[2128500806] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"1.13316276s","start":"2026-02-20T18:24:37.09389Z","end":"2026-02-20T18:24:38.227053Z","steps":["trace[2128500806] 'process raft request' (duration: 1.085936264s)","trace[2128500806] 'compare' (duration: 46.464553ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:38.227283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:37.093881Z","time spent":"1.133320573s","remote":"127.0.0.1:37962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:38.227501Z","caller":"traceutil/trace.go:171","msg":"trace[1300867495] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"1.132834214s","start":"2026-02-20T18:24:37.094624Z","end":"2026-02-20T18:24:38.227458Z","steps":["trace[1300867495] 'process raft request' (duration: 1.132147232s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:38.227757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:37.094617Z","time spent":"1.132984017s","remote":"127.0.0.1:37600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4794,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:40.814589Z","caller":"traceutil/trace.go:171","msg":"trace[1371429343] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"320.567511ms","start":"2026-02-20T18:24:40.494007Z","end":"2026-02-20T18:24:40.814575Z","steps":["trace[1371429343] 'read index received' (duration: 320.434128ms)","trace[1371429343] 'applied index is now lower than readState.Index' (duration: 132.693µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:40.814616Z","caller":"traceutil/trace.go:171","msg":"trace[1452330102] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"376.582391ms","start":"2026-02-20T18:24:40.438016Z","end":"2026-02-20T18:24:40.814598Z","steps":["trace[1452330102] 'process raft request' (duration: 376.462679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:40.814714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.704903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:24:40.814714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:40.438006Z","time spent":"376.663732ms","remote":"127.0.0.1:37500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:24:40.814735Z","caller":"traceutil/trace.go:171","msg":"trace[623385669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"320.780574ms","start":"2026-02-20T18:24:40.493948Z","end":"2026-02-20T18:24:40.814729Z","steps":["trace[623385669] 'agreement among raft nodes before linearized reading' (duration: 320.707613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:40.814753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:40.493926Z","time spent":"320.822535ms","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:40.814919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.272707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:24:40.81494Z","caller":"traceutil/trace.go:171","msg":"trace[1951429544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"295.331028ms","start":"2026-02-20T18:24:40.519604Z","end":"2026-02-20T18:24:40.814935Z","steps":["trace[1951429544] 'agreement among raft nodes before linearized reading' (duration: 295.264727ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:59.549256Z","caller":"traceutil/trace.go:171","msg":"trace[1462406870] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"116.223093ms","start":"2026-02-20T18:25:59.432987Z","end":"2026-02-20T18:25:59.54921Z","steps":["trace[1462406870] 'process raft request' (duration: 61.615442ms)","trace[1462406870] 'compare' (duration: 54.358118ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:59.75279Z","caller":"traceutil/trace.go:171","msg":"trace[56865017] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"318.785058ms","start":"2026-02-20T18:25:59.433943Z","end":"2026-02-20T18:25:59.752728Z","steps":["trace[56865017] 'process raft request' (duration: 304.772244ms)","trace[56865017] 'compare' (duration: 13.626129ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:25:59.752951Z","caller":"traceutil/trace.go:171","msg":"trace[1913490695] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"318.734737ms","start":"2026-02-20T18:25:59.434193Z","end":"2026-02-20T18:25:59.752928Z","steps":["trace[1913490695] 'process raft request' (duration: 318.550425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:25:59.75315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:59.434162Z","time spent":"318.864119ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:25:59.753098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:25:59.433903Z","time spent":"319.081441ms","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:25:59.753312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.048988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:25:59.753504Z","caller":"traceutil/trace.go:171","msg":"trace[1669257909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"132.280222ms","start":"2026-02-20T18:25:59.621173Z","end":"2026-02-20T18:25:59.753453Z","steps":["trace[1669257909] 'agreement among raft nodes before linearized reading' (duration: 131.851047ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:25:59.753777Z","caller":"traceutil/trace.go:171","msg":"trace[1752592326] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1058; }","duration":"131.595493ms","start":"2026-02-20T18:25:59.62123Z","end":"2026-02-20T18:25:59.752825Z","steps":["trace[1752592326] 'read index received' (duration: 117.384466ms)","trace[1752592326] 'applied index is now lower than readState.Index' (duration: 14.210007ms)"],"step_count":2}